[09:16:57.325] New invocation is queued and will start shortly
[09:16:58.424] Starting the invocation (attempt 1)
[09:16:58.460] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:16:58.462] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:16:58.462] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022392924789396144",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d2ed15db6bc5f08f57505e034726bb0976981a10\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022392924789396144",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d2ed15db6bc5f08f57505e034726bb0976981a10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2ed15db6bc5f08f57505e034726bb0976981a10",
"gitiles_ref:refs/heads/master"
]
}
[09:16:59.238] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611739018551836",
"id": "8856962862842155488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d2ed15db6bc5f08f57505e034726bb0976981a10\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611739019088445",
"tags": [
"build_address:luci.chromium.ci/win-asan/17033",
"builder:win-asan",
"buildset:commit/git/d2ed15db6bc5f08f57505e034726bb0976981a10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2ed15db6bc5f08f57505e034726bb0976981a10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022392924789396144",
"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/8856962862842155488/+/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": "1611739019088593",
"url": "https://ci.chromium.org/b/8856962862842155488",
"utcnow_ts": "1611739019231197"
}
}
[09:16:59.238] Task URL: https://ci.chromium.org/b/8856962862842155488
[09:16:59.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:2:0) after 1m0s
[09:17:59.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:2:0)
[09:17:59.256] Timer tick, asking Buildbucket for the build status
[09:17:59.353] Build 8856962862842155488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:59.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:3:0) after 1m0s
[09:18:59.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:3:0)
[09:18:59.775] Timer tick, asking Buildbucket for the build status
[09:18:59.868] Build 8856962862842155488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:59.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:4:0) after 1m0s
[09:19:59.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:4:0)
[09:19:59.883] Timer tick, asking Buildbucket for the build status
[09:19:59.981] Build 8856962862842155488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:59.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:5:0) after 1m0s
[09:20:59.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:5:0)
[09:20:59.988] Timer tick, asking Buildbucket for the build status
[09:21:00.157] Build 8856962862842155488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:00.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:6:0) after 1m0s
[09:22:00.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:6:0)
[09:22:00.196] Timer tick, asking Buildbucket for the build status
[09:22:00.326] Build 8856962862842155488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:00.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:7:0) after 1m0s
[09:22:07.529] Received PubSub notification, asking Buildbucket for the build status
[09:22:07.607] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:00.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:7:0)
[09:23:00.347] Timer tick, asking Buildbucket for the build status
[09:23:00.429] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:00.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:9:0) after 1m0s
[09:24:00.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:9:0)
[09:24:00.471] Timer tick, asking Buildbucket for the build status
[09:24:00.772] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:00.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:10:0) after 1m0s
[09:25:00.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:10:0)
[09:25:00.901] Timer tick, asking Buildbucket for the build status
[09:25:00.972] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:00.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:11:0) after 1m0s
[09:26:01.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:11:0)
[09:26:01.022] Timer tick, asking Buildbucket for the build status
[09:26:02.790] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:02.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:12:0) after 1m0s
[09:27:02.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:12:0)
[09:27:02.839] Timer tick, asking Buildbucket for the build status
[09:27:03.025] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:03.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:13:0) after 1m0s
[09:28:03.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:13:0)
[09:28:03.068] Timer tick, asking Buildbucket for the build status
[09:28:03.167] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:03.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:14:0) after 1m0s
[09:29:03.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:14:0)
[09:29:03.226] Timer tick, asking Buildbucket for the build status
[09:29:03.286] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:03.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:15:0) after 1m0s
[09:30:03.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:15:0)
[09:30:03.481] Timer tick, asking Buildbucket for the build status
[09:30:03.584] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:03.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:16:0) after 1m0s
[09:31:03.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:16:0)
[09:31:03.557] Timer tick, asking Buildbucket for the build status
[09:31:03.649] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:03.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:17:0) after 1m0s
[09:32:03.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:17:0)
[09:32:03.728] Timer tick, asking Buildbucket for the build status
[09:32:03.861] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:03.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:18:0) after 1m0s
[09:33:03.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:18:0)
[09:33:03.884] Timer tick, asking Buildbucket for the build status
[09:33:03.992] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:03.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:19:0) after 1m0s
[09:34:04.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:19:0)
[09:34:04.059] Timer tick, asking Buildbucket for the build status
[09:34:04.144] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:04.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:20:0) after 1m0s
[09:35:04.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:20:0)
[09:35:04.215] Timer tick, asking Buildbucket for the build status
[09:35:04.343] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:04.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:21:0) after 1m0s
[09:36:04.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:21:0)
[09:36:04.458] Timer tick, asking Buildbucket for the build status
[09:36:04.539] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:04.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:22:0) after 1m0s
[09:37:04.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:22:0)
[09:37:04.639] Timer tick, asking Buildbucket for the build status
[09:37:04.934] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:04.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:23:0) after 1m0s
[09:38:05.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:23:0)
[09:38:05.005] Timer tick, asking Buildbucket for the build status
[09:38:05.700] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:05.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:24:0) after 1m0s
[09:39:05.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:24:0)
[09:39:05.864] Timer tick, asking Buildbucket for the build status
[09:39:06.410] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:06.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:25:0) after 1m0s
[09:40:06.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:25:0)
[09:40:06.496] Timer tick, asking Buildbucket for the build status
[09:40:07.282] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:07.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:26:0) after 1m0s
[09:41:07.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:26:0)
[09:41:07.358] Timer tick, asking Buildbucket for the build status
[09:41:07.433] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:07.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:27:0) after 1m0s
[09:42:07.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:27:0)
[09:42:07.548] Timer tick, asking Buildbucket for the build status
[09:42:07.654] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:07.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:28:0) after 1m0s
[09:43:07.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:28:0)
[09:43:07.777] Timer tick, asking Buildbucket for the build status
[09:43:08.885] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:08.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:29:0) after 1m0s
[09:44:09.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:29:0)
[09:44:09.364] Timer tick, asking Buildbucket for the build status
[09:44:09.511] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:09.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:30:0) after 1m0s
[09:45:09.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:30:0)
[09:45:09.711] Timer tick, asking Buildbucket for the build status
[09:45:09.926] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:09.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:31:0) after 1m0s
[09:46:09.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:31:0)
[09:46:09.949] Timer tick, asking Buildbucket for the build status
[09:46:10.847] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:10.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:32:0) after 1m0s
[09:47:10.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:32:0)
[09:47:10.821] Timer tick, asking Buildbucket for the build status
[09:47:11.168] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:11.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:33:0) after 1m0s
[09:48:11.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:33:0)
[09:48:11.218] Timer tick, asking Buildbucket for the build status
[09:48:11.838] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:11.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:34:0) after 1m0s
[09:49:11.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:34:0)
[09:49:11.825] Timer tick, asking Buildbucket for the build status
[09:49:12.705] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:12.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:35:0) after 1m0s
[09:50:12.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:35:0)
[09:50:12.740] Timer tick, asking Buildbucket for the build status
[09:50:13.154] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:13.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:36:0) after 1m0s
[09:51:13.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:36:0)
[09:51:13.159] Timer tick, asking Buildbucket for the build status
[09:51:13.241] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:13.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:37:0) after 1m0s
[09:52:13.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:37:0)
[09:52:13.293] Timer tick, asking Buildbucket for the build status
[09:52:13.447] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:13.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:38:0) after 1m0s
[09:53:13.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:38:0)
[09:53:13.497] Timer tick, asking Buildbucket for the build status
[09:53:13.624] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:13.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:39:0) after 1m0s
[09:54:13.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:39:0)
[09:54:13.699] Timer tick, asking Buildbucket for the build status
[09:54:13.945] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:40:0) after 1m0s
[09:55:13.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:40:0)
[09:55:13.966] Timer tick, asking Buildbucket for the build status
[09:55:14.040] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:14.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:41:0) after 1m0s
[09:56:14.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:41:0)
[09:56:14.059] Timer tick, asking Buildbucket for the build status
[09:56:14.151] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:14.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:42:0) after 1m0s
[09:57:14.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:42:0)
[09:57:14.182] Timer tick, asking Buildbucket for the build status
[09:57:14.312] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:14.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:43:0) after 1m0s
[09:58:14.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:43:0)
[09:58:14.331] Timer tick, asking Buildbucket for the build status
[09:58:14.409] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:14.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:44:0) after 1m0s
[09:59:14.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:44:0)
[09:59:14.430] Timer tick, asking Buildbucket for the build status
[09:59:14.524] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:14.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:45:0) after 1m0s
[10:00:14.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:45:0)
[10:00:14.687] Timer tick, asking Buildbucket for the build status
[10:00:14.844] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:14.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:46:0) after 1m0s
[10:01:14.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:46:0)
[10:01:14.930] Timer tick, asking Buildbucket for the build status
[10:01:15.859] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:15.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:47:0) after 1m0s
[10:02:15.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:47:0)
[10:02:15.897] Timer tick, asking Buildbucket for the build status
[10:02:16.023] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:16.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:48:0) after 1m0s
[10:03:16.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:48:0)
[10:03:16.044] Timer tick, asking Buildbucket for the build status
[10:03:16.112] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:16.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:49:0) after 1m0s
[10:04:16.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:49:0)
[10:04:16.126] Timer tick, asking Buildbucket for the build status
[10:04:16.244] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:16.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:50:0) after 1m0s
[10:05:16.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:50:0)
[10:05:16.286] Timer tick, asking Buildbucket for the build status
[10:05:16.566] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:16.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:51:0) after 1m0s
[10:06:16.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:51:0)
[10:06:16.625] Timer tick, asking Buildbucket for the build status
[10:06:16.695] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:16.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:52:0) after 1m0s
[10:07:16.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:52:0)
[10:07:16.766] Timer tick, asking Buildbucket for the build status
[10:07:16.888] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:16.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:53:0) after 1m0s
[10:08:17.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:53:0)
[10:08:17.041] Timer tick, asking Buildbucket for the build status
[10:08:18.484] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:18.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:54:0) after 1m0s
[10:09:18.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:54:0)
[10:09:18.567] Timer tick, asking Buildbucket for the build status
[10:09:18.633] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:18.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:55:0) after 1m0s
[10:10:18.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:55:0)
[10:10:18.947] Timer tick, asking Buildbucket for the build status
[10:10:19.001] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:56:0) after 1m0s
[10:11:19.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:56:0)
[10:11:19.388] Timer tick, asking Buildbucket for the build status
[10:11:19.876] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:19.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:57:0) after 1m0s
[10:12:19.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:57:0)
[10:12:19.897] Timer tick, asking Buildbucket for the build status
[10:12:20.062] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:20.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:58:0) after 1m0s
[10:13:20.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:58:0)
[10:13:20.062] Timer tick, asking Buildbucket for the build status
[10:13:20.147] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:20.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:59:0) after 1m0s
[10:14:20.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:59:0)
[10:14:20.126] Timer tick, asking Buildbucket for the build status
[10:14:20.190] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:20.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:60:0) after 1m0s
[10:15:20.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:60:0)
[10:15:20.253] Timer tick, asking Buildbucket for the build status
[10:15:20.372] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:20.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:61:0) after 1m0s
[10:16:20.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:61:0)
[10:16:20.411] Timer tick, asking Buildbucket for the build status
[10:16:20.489] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:20.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:62:0) after 1m0s
[10:17:20.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:62:0)
[10:17:20.654] Timer tick, asking Buildbucket for the build status
[10:17:20.867] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:20.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:63:0) after 1m0s
[10:18:20.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:63:0)
[10:18:20.951] Timer tick, asking Buildbucket for the build status
[10:18:21.127] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:21.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:64:0) after 1m0s
[10:19:21.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:64:0)
[10:19:21.172] Timer tick, asking Buildbucket for the build status
[10:19:21.397] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:21.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:65:0) after 1m0s
[10:20:21.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:65:0)
[10:20:21.444] Timer tick, asking Buildbucket for the build status
[10:20:21.537] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:21.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:66:0) after 1m0s
[10:21:21.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:66:0)
[10:21:21.621] Timer tick, asking Buildbucket for the build status
[10:21:21.690] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:21.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:67:0) after 1m0s
[10:22:21.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:67:0)
[10:22:21.712] Timer tick, asking Buildbucket for the build status
[10:22:21.798] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:68:0) after 1m0s
[10:23:21.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:68:0)
[10:23:21.817] Timer tick, asking Buildbucket for the build status
[10:23:21.873] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:21.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:69:0) after 1m0s
[10:24:21.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:69:0)
[10:24:21.879] Timer tick, asking Buildbucket for the build status
[10:24:22.181] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:22.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:70:0) after 1m0s
[10:25:22.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:70:0)
[10:25:22.273] Timer tick, asking Buildbucket for the build status
[10:25:22.514] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:22.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:71:0) after 1m0s
[10:26:22.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:71:0)
[10:26:22.543] Timer tick, asking Buildbucket for the build status
[10:26:22.671] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:22.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:72:0) after 1m0s
[10:27:22.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:72:0)
[10:27:22.696] Timer tick, asking Buildbucket for the build status
[10:27:22.782] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:22.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:73:0) after 1m0s
[10:28:22.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:73:0)
[10:28:22.764] Timer tick, asking Buildbucket for the build status
[10:28:22.905] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:22.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:74:0) after 1m0s
[10:29:22.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:74:0)
[10:29:22.926] Timer tick, asking Buildbucket for the build status
[10:29:23.053] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:23.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:75:0) after 1m0s
[10:30:23.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:75:0)
[10:30:23.248] Timer tick, asking Buildbucket for the build status
[10:30:23.346] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:23.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:76:0) after 1m0s
[10:31:23.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:76:0)
[10:31:23.527] Timer tick, asking Buildbucket for the build status
[10:31:23.640] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:23.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:77:0) after 1m0s
[10:32:23.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:77:0)
[10:32:23.907] Timer tick, asking Buildbucket for the build status
[10:32:24.084] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:24.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:78:0) after 1m0s
[10:33:24.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:78:0)
[10:33:24.503] Timer tick, asking Buildbucket for the build status
[10:33:25.627] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:25.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:79:0) after 1m0s
[10:34:25.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:79:0)
[10:34:25.656] Timer tick, asking Buildbucket for the build status
[10:34:25.776] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:25.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:80:0) after 1m0s
[10:35:25.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:80:0)
[10:35:25.950] Timer tick, asking Buildbucket for the build status
[10:35:26.095] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:26.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:81:0) after 1m0s
[10:36:26.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:81:0)
[10:36:26.176] Timer tick, asking Buildbucket for the build status
[10:36:26.307] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:26.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:82:0) after 1m0s
[10:37:26.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:82:0)
[10:37:26.358] Timer tick, asking Buildbucket for the build status
[10:37:26.475] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:26.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:83:0) after 1m0s
[10:38:26.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:83:0)
[10:38:26.532] Timer tick, asking Buildbucket for the build status
[10:38:26.652] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:26.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:84:0) after 1m0s
[10:39:26.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:84:0)
[10:39:26.787] Timer tick, asking Buildbucket for the build status
[10:39:26.926] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:26.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:85:0) after 1m0s
[10:40:27.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:85:0)
[10:40:27.042] Timer tick, asking Buildbucket for the build status
[10:40:27.173] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:27.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:86:0) after 1m0s
[10:41:27.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:86:0)
[10:41:27.213] Timer tick, asking Buildbucket for the build status
[10:41:27.334] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:27.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:87:0) after 1m0s
[10:42:27.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:87:0)
[10:42:27.405] Timer tick, asking Buildbucket for the build status
[10:42:27.564] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:27.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:88:0) after 1m0s
[10:43:27.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:88:0)
[10:43:27.721] Timer tick, asking Buildbucket for the build status
[10:43:27.861] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:27.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:89:0) after 1m0s
[10:44:27.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:89:0)
[10:44:27.924] Timer tick, asking Buildbucket for the build status
[10:44:28.054] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:28.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:90:0) after 1m0s
[10:45:28.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:90:0)
[10:45:28.081] Timer tick, asking Buildbucket for the build status
[10:45:28.239] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:28.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:91:0) after 1m0s
[10:46:28.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:91:0)
[10:46:28.262] Timer tick, asking Buildbucket for the build status
[10:46:28.424] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:28.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:92:0) after 1m0s
[10:47:28.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:92:0)
[10:47:28.526] Timer tick, asking Buildbucket for the build status
[10:47:28.657] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:28.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:93:0) after 1m0s
[10:48:28.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:93:0)
[10:48:28.680] Timer tick, asking Buildbucket for the build status
[10:48:28.808] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:28.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:94:0) after 1m0s
[10:49:28.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:94:0)
[10:49:28.867] Timer tick, asking Buildbucket for the build status
[10:49:28.978] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:28.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:95:0) after 1m0s
[10:50:29.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:95:0)
[10:50:29.062] Timer tick, asking Buildbucket for the build status
[10:50:29.356] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:29.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:96:0) after 1m0s
[10:51:29.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:96:0)
[10:51:29.547] Timer tick, asking Buildbucket for the build status
[10:51:29.726] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:29.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:97:0) after 1m0s
[10:52:29.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:97:0)
[10:52:29.848] Timer tick, asking Buildbucket for the build status
[10:52:29.963] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:29.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:98:0) after 1m0s
[10:53:30.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:98:0)
[10:53:30.071] Timer tick, asking Buildbucket for the build status
[10:53:30.173] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:30.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:99:0) after 1m0s
[10:54:30.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:99:0)
[10:54:30.300] Timer tick, asking Buildbucket for the build status
[10:54:30.432] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:30.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:100:0) after 1m0s
[10:55:30.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:100:0)
[10:55:30.545] Timer tick, asking Buildbucket for the build status
[10:55:30.912] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:30.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:101:0) after 1m0s
[10:56:30.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:101:0)
[10:56:30.968] Timer tick, asking Buildbucket for the build status
[10:56:31.394] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:31.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:102:0) after 1m0s
[10:57:31.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:102:0)
[10:57:31.452] Timer tick, asking Buildbucket for the build status
[10:57:31.886] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:31.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:103:0) after 1m0s
[10:58:31.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:103:0)
[10:58:31.905] Timer tick, asking Buildbucket for the build status
[10:58:32.197] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:32.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:104:0) after 1m0s
[10:59:32.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:104:0)
[10:59:32.234] Timer tick, asking Buildbucket for the build status
[10:59:32.336] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:32.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:105:0) after 1m0s
[11:00:32.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:105:0)
[11:00:32.436] Timer tick, asking Buildbucket for the build status
[11:00:32.768] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:32.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:106:0) after 1m0s
[11:01:32.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:106:0)
[11:01:32.826] Timer tick, asking Buildbucket for the build status
[11:01:32.938] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:32.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:107:0) after 1m0s
[11:02:33.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:107:0)
[11:02:33.007] Timer tick, asking Buildbucket for the build status
[11:02:33.180] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:33.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:108:0) after 1m0s
[11:03:33.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:108:0)
[11:03:33.265] Timer tick, asking Buildbucket for the build status
[11:03:33.449] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:33.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:109:0) after 1m0s
[11:04:33.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:109:0)
[11:04:33.654] Timer tick, asking Buildbucket for the build status
[11:04:33.744] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:33.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:110:0) after 1m0s
[11:05:33.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:110:0)
[11:05:33.824] Timer tick, asking Buildbucket for the build status
[11:05:34.263] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:34.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:111:0) after 1m0s
[11:06:34.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:111:0)
[11:06:34.367] Timer tick, asking Buildbucket for the build status
[11:06:35.283] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:35.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:112:0) after 1m0s
[11:07:35.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:112:0)
[11:07:35.318] Timer tick, asking Buildbucket for the build status
[11:07:35.761] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:35.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:113:0) after 1m0s
[11:08:35.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:113:0)
[11:08:35.818] Timer tick, asking Buildbucket for the build status
[11:08:36.255] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:36.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:114:0) after 1m0s
[11:09:36.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:114:0)
[11:09:36.309] Timer tick, asking Buildbucket for the build status
[11:09:36.523] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:36.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:115:0) after 1m0s
[11:10:36.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:115:0)
[11:10:36.627] Timer tick, asking Buildbucket for the build status
[11:10:36.822] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:36.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:116:0) after 1m0s
[11:11:36.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:116:0)
[11:11:36.865] Timer tick, asking Buildbucket for the build status
[11:11:36.974] Build 8856962862842155488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:36.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022392924789396144:117:0) after 1m0s
[11:12:26.898] Received PubSub notification, asking Buildbucket for the build status
[11:12:26.985] Build 8856962862842155488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:12:26.985] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611745944920520",
"created_by": "project:chromium",
"created_ts": "1611739018551836",
"id": "8856962862842155488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d2ed15db6bc5f08f57505e034726bb0976981a10\"}}",
"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\": \"d2ed15db6bc5f08f57505e034726bb0976981a10\", \"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-0-rbnr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17033, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"c38413f86cb98f088043c05eac900e7a9986d52a\", \"got_dawn_revision\": \"2df77f4325841e667e6b9ff9921fd626496a3b64\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d2ed15db6bc5f08f57505e034726bb0976981a10\", \"got_revision_cp\": \"refs/heads/master@{#847547}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"aec0826aba0db51914209ec85a515d3a99311f07\", \"got_v8_revision\": \"ca438ce0794e4f833cc87f59232139df05f0ae73\", \"got_v8_revision_cp\": \"refs/heads/9.0.84@{#1}\", \"got_webrtc_revision\": \"fae4fb13451bea44ae1a94509befe8d7c6b11eb6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33077}\", \"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\": \"d2ed15db6bc5f08f57505e034726bb0976981a10\", \"swarm_hashes_refs/heads/master(at){#847547}_without_patch\": {\"absl_hardening_tests\": \"672c303005c47fb6f6242d0f1fc5bce6f2e2e605\", \"accessibility_unittests\": \"a86a1d5a810ae3fc380a0d7b1450eed1f8f564d3\", \"angle_unittests\": \"1667121a0234e5be7b2fd34a9987c22cbb6d7caf\", \"app_shell_unittests\": \"579fb7392d50b17734c019cc0d460e4b581c6472\", \"aura_unittests\": \"9a9996d002c9ec1404863e6ef0de1f1d709a5876\", \"base_unittests\": \"720d268a167293a67be6dffe96ce47c8a554140e\", \"base_util_unittests\": \"0d3151cefc9fe440fd12c9fdf67b27cc3b72f5ca\", \"blink_common_unittests\": \"8128f2048fa43d7bb86c0e598f0b303280fda379\", \"blink_fuzzer_unittests\": \"39d285090f3cf8572fd9c86607e6b1fc98d69b9f\", \"blink_heap_unittests\": \"1f9888d1ee3ae72327e5470e42338b15ef9b3263\", \"blink_platform_unittests\": \"6caa0974e2c4edf519f46de981d4298e93924014\", \"blink_unittests\": \"2e3575692c02e8f650905f5cee53b170dfc7095e\", \"boringssl_crypto_tests\": \"ae3610b923def3082a94fb27747f4797d572ffdc\", \"boringssl_ssl_tests\": \"6fdc02976037655b38d3ec74b0c0b65188845c4a\", \"browser_switcher_bho_unittests\": \"9ab408d8df28e9a607f54a563816ce54991a0e7b\", \"browser_tests\": \"53f201835567aea92a2ca5eb09db485441fd3187\", \"capture_unittests\": \"33cfbccf9be1a74b3e922d916c81903b96e1ecc2\", \"cast_unittests\": \"87b2f87888f18251976f142b3b5a9466e04d8143\", \"cc_unittests\": \"9106b5f07400535e643306ae93a92ae15eb587f9\", \"chrome_app_unittests\": \"0c0a6b959c397b47355d9f4436af74a315c8469b\", \"chrome_cleaner_unittests\": \"7666ed36c59cbfab081deec028692083e0cc44cf\", \"chrome_elf_unittests\": \"8656225287953eca63331bc45f9cc20e288e9eeb\", \"chromedriver_unittests\": \"eb9f619c30626f49eadccb61468fe90246afbece\", \"components_browsertests\": \"10ea0005afeb39cf5bb6d940c805bb2976cc14a4\", \"components_unittests\": \"843ca8bb28ea7a996dfd1c3aee885e0ee2377ffa\", \"compositor_unittests\": \"2a143010e051507c7ae06aa80d86cbb11b508cc6\", \"content_browsertests\": \"68985d7989a7c1e9007f8f2594437b6e51720ce7\", \"content_unittests\": \"2289265b4991c54b7f2f382d67e90b5966d59d9f\", \"courgette_unittests\": \"d978fda8431adc4f634804ad71f226d34e276e34\", \"crashpad_tests\": \"946d2223d1b1d93199d6a543f4584c3cdce8aa3b\", \"cronet_tests\": \"49cec3c3764e1a992caaca42e2c2b330f45ebb75\", \"cronet_unittests\": \"86145917e71cab363cdc79b2d6098b94e3dbaa33\", \"crypto_unittests\": \"3879dd7ec251680f307217c6512fb64d12f37a6b\", \"delayloads_unittests\": \"0a75ed073564bb5c98ebaddd989efc824152500c\", \"device_unittests\": \"1bb1027ee72c8405ee95e6c0483662c75519e9b4\", \"display_unittests\": \"0d78cd468582462f6f7290c86e5355f282703d4c\", \"elevation_service_unittests\": \"21a0d2040e489cd963a0e4e4939f0f1165238dc4\", \"events_unittests\": \"a7d86d5c42a8c47be2acbaa343b245bfbd9c5ce2\", \"extensions_browsertests\": \"80a02317791be1b7a43247a23791aa5d318c85c9\", \"extensions_unittests\": \"fd61d7ceedbcf75b82f104abb16a0a264d765563\", \"filesystem_service_unittests\": \"239df9b860052caa089ba4b757e4dbd49950745c\", \"gcm_unit_tests\": \"cd80ad835ddb90eca90255043f294ef391fdd57d\", \"gcp_unittests\": \"8ef30983f437d3d25d63d99b29e610eed46fe731\", \"gfx_unittests\": \"d09a1c3401e2e1d7cb835b61e8dfe0cc828f596d\", \"gin_unittests\": \"1f7c06a2d51c8e9e8b20cca5e64267cd9fdb76d6\", \"google_apis_unittests\": \"21bd2c19323a0c453be87d04e3dbc509eddff9ee\", \"gpu_unittests\": \"5ea8732b540397e68f29cf44351d200cba400249\", \"gwp_asan_unittests\": \"3abd3099e52a6004e5bef9a345defdb129a16d57\", \"headless_browsertests\": \"031f5c0aca35f1d76a7704df63721158d2899c3d\", \"headless_unittests\": \"1a0bce6a0d1cd772557ff41114f7ef6f96bcea51\", \"install_static_unittests\": \"d7afe35b44df9d0cb50bfbc9353edc6b20ad4b84\", \"installer_util_unittests\": \"ac22d668b639b94aa2146d19f983c5ddf2e49849\", \"interactive_ui_tests\": \"2a3661e540e4621359944538c193431788bd81de\", \"ipc_tests\": \"4e8bef3ffb5f60d978ca5f6ba4c566faccb25783\", \"jingle_unittests\": \"32e8dafd5e2b8efce90baf83a41c9efb7457fa78\", \"latency_unittests\": \"874d932e7b5ebdc533cfa159b76c07ed6caa609c\", \"libjingle_xmpp_unittests\": \"79deeddc0a009b7c43644fa75aebcab1da9e6de8\", \"liburlpattern_unittests\": \"4604f93e6ce9891e552257f0cff12d8852846646\", \"media_blink_unittests\": \"f52fd1bafd6a67e83e47fd5d18ac8516561a957a\", \"media_unittests\": \"c947623b81089893d4b04198f8bedc859b7dc494\", \"message_center_unittests\": \"a80411acf6b4e8b2b79e17061049bb70d33f8d01\", \"midi_unittests\": \"80431f09e34d96966704629dbaf3ee39a553c856\", \"mojo_core_unittests\": \"69ec23f1599e568b6305a1f7f76ce21297df8cd0\", \"mojo_unittests\": \"3756bef6cff299e5e60248fac4329f0ad869d65d\", \"nacl_loader_unittests\": \"3128ff33c84ae3383a9fb938b17840f4ae19b11e\", \"native_theme_unittests\": \"4d512da0b8e67fe1dffeec76d4feb995680cebf8\", \"net_unittests\": \"fc02a8037fa1d3c4833fa5527e063155385fe9ba\", \"notification_helper_unittests\": \"fd72c4dd447bdf0925125dba9b45f3adac45e0b2\", \"pdf_unittests\": \"041fe5dc36bc543da9eb542d6f556b5181413a3c\", \"perfetto_unittests\": \"8bfa66543746df67e5c337b5e06042f9d0204ad5\", \"ppapi_unittests\": \"95a9666e5e773848f1d20f6ded32f032e55fcf28\", \"printing_unittests\": \"7fe1408bf8a3cb32a95e31051f95d5a020848ea3\", \"remoting_unittests\": \"4dd6507e7e155e2bc0b2929968cfe58c3c638dbd\", \"sbox_integration_tests\": \"28a8cae8c3bd449d0dffb0c2dc92df7337d4e778\", \"sbox_unittests\": \"46feba20babcbabf0122301e7d948dd3f28beb77\", \"sbox_validation_tests\": \"fca9b9ebfbd940da602937f72e3654ed880a75ac\", \"service_manager_unittests\": \"3af3e36252b760cda7b7360a2d58057feffaea20\", \"services_unittests\": \"ad2491f0fa55d1e603637cb28b03673fc7edbcc2\", \"setup_unittests\": \"85886d384f10e812d25d34a78a305942a250c429\", \"shell_dialogs_unittests\": \"8de626cd04861952e3a2d6ba94764aa68e382a38\", \"skia_unittests\": \"7ac73291792b39b2cf4ce2d63a801c8b40d7d70e\", \"snapshot_unittests\": \"a12caa2e6cea1b39ea18b664e6def976543b7881\", \"sql_unittests\": \"1236c5ebaa85e237a10cc53168ff2cb4626c3096\", \"storage_unittests\": \"77cb961ea2fa11e2b1c71427dc174acd50ff54c1\", \"sync_integration_tests\": \"fd49b0b6e0728786683dd587ed5c17e973cb32b6\", \"traffic_annotation_auditor_unittests\": \"a98cefcf94bf26e07f9e96709322a89006a363b6\", \"ui_base_unittests\": \"34d242d000e7cebdd30378fa971d82537981f422\", \"ui_touch_selection_unittests\": \"fdb1f78c2bfcfb2ee2839e7fb9f45953df9ac2d8\", \"unit_tests\": \"27bc217c32e31022bbc54167d11b21086c3a2961\", \"updater_tests\": \"d5d5271f566afd83b325ef40136f2d06526629ef\", \"url_unittests\": \"265297fd5f428f68d51e108243dc68124dd5564f\", \"views_unittests\": \"92d10ecb8a211c890c901d7e2ea6beabf230c76e\", \"viz_unittests\": \"687c1bf3b7f84786a854d34edb45cca5685015f1\", \"vr_common_unittests\": \"6aa200c051b6990edd4b6cc45345ec13e6d6474c\", \"vr_pixeltests\": \"d6bda9ac1be51fa0369ac9a97f63143f6331a1ee\", \"weblayer_browsertests\": \"4254352a606494293860e3cd15171acd326ab415\", \"weblayer_unittests\": \"97f5ee38e7f2bf8caf79a8513162dba378d14b4e\", \"wm_unittests\": \"1d256045cc93ced4cf1c64c1b35898ee1ff50de7\", \"wtf_unittests\": \"54993dbe66fa2f4429042c94d8bee832e6815078\", \"zlib_unittests\": \"4f19e9af9a146541485a26dd8fe18e277230151f\", \"zucchini_unittests\": \"3e542fdba20eb6f7d2d4b31d446eb891e9b04181\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-rbnr\"], \"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\": [\"5672-4713de1\"], \"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": "1611739326401359",
"status": "COMPLETED",
"status_changed_ts": "1611745945601279",
"tags": [
"build_address:luci.chromium.ci/win-asan/17033",
"builder:win-asan",
"buildset:commit/git/d2ed15db6bc5f08f57505e034726bb0976981a10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2ed15db6bc5f08f57505e034726bb0976981a10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022392924789396144",
"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/8856962862842155488/+/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:515bf340e1a43810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611745945659652",
"url": "https://ci.chromium.org/b/8856962862842155488",
"utcnow_ts": "1611745946976913"
}
}
[11:12:26.985] Invocation finished in 1h55m29.67534656s with status SUCCEEDED