[14:16:45.074] New invocation is queued and will start shortly
[14:16:46.159] Starting the invocation (attempt 1)
[14:16:46.230] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:16:46.230] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:16:46.230] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018116005846693008",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cf672362b614bbbdc02b615cfb53568a88a71887\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018116005846693008",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/cf672362b614bbbdc02b615cfb53568a88a71887",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf672362b614bbbdc02b615cfb53568a88a71887",
"gitiles_ref:refs/heads/master"
]
}
[14:16:47.933] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1615817806405793",
"id": "8852685943789145040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cf672362b614bbbdc02b615cfb53568a88a71887\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615817807821089",
"tags": [
"build_address:luci.chromium.ci/win-asan/17803",
"builder:win-asan",
"buildset:commit/git/cf672362b614bbbdc02b615cfb53568a88a71887",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf672362b614bbbdc02b615cfb53568a88a71887",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018116005846693008",
"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/8852685943789145040/+/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": "1615817807821194",
"url": "https://ci.chromium.org/b/8852685943789145040",
"utcnow_ts": "1615817807924981"
}
}
[14:16:47.933] Task URL: https://ci.chromium.org/b/8852685943789145040
[14:16:47.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:2:0) after 1m0s
[14:17:48.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:2:0)
[14:17:48.244] Timer tick, asking Buildbucket for the build status
[14:17:48.414] Build 8852685943789145040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:48.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:3:0) after 1m0s
[14:18:48.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:3:0)
[14:18:48.835] Timer tick, asking Buildbucket for the build status
[14:18:48.891] Build 8852685943789145040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:48.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:4:0) after 1m0s
[14:19:48.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:4:0)
[14:19:48.941] Timer tick, asking Buildbucket for the build status
[14:19:49.098] Build 8852685943789145040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:49.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:5:0) after 1m0s
[14:20:13.843] Received PubSub notification, asking Buildbucket for the build status
[14:20:14.022] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:49.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:5:0)
[14:20:49.317] Timer tick, asking Buildbucket for the build status
[14:20:49.420] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:49.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:7:0) after 1m0s
[14:21:49.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:7:0)
[14:21:49.497] Timer tick, asking Buildbucket for the build status
[14:21:49.561] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:49.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:8:0) after 1m0s
[14:22:49.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:8:0)
[14:22:49.869] Timer tick, asking Buildbucket for the build status
[14:22:49.937] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:49.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:9:0) after 1m0s
[14:23:50.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:9:0)
[14:23:50.467] Timer tick, asking Buildbucket for the build status
[14:23:50.547] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:50.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:10:0) after 1m0s
[14:24:50.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:10:0)
[14:24:50.896] Timer tick, asking Buildbucket for the build status
[14:24:51.060] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:51.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:11:0) after 1m0s
[14:25:51.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:11:0)
[14:25:51.328] Timer tick, asking Buildbucket for the build status
[14:25:51.390] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:51.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:12:0) after 1m0s
[14:26:51.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:12:0)
[14:26:51.566] Timer tick, asking Buildbucket for the build status
[14:26:51.701] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:51.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:13:0) after 1m0s
[14:27:51.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:13:0)
[14:27:51.819] Timer tick, asking Buildbucket for the build status
[14:27:51.924] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:51.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:14:0) after 1m0s
[14:28:51.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:14:0)
[14:28:51.926] Timer tick, asking Buildbucket for the build status
[14:28:52.047] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:52.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:15:0) after 1m0s
[14:29:52.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:15:0)
[14:29:52.111] Timer tick, asking Buildbucket for the build status
[14:29:52.445] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:52.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:16:0) after 1m0s
[14:30:52.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:16:0)
[14:30:52.507] Timer tick, asking Buildbucket for the build status
[14:30:52.583] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:52.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:17:0) after 1m0s
[14:31:52.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:17:0)
[14:31:52.805] Timer tick, asking Buildbucket for the build status
[14:31:53.050] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:53.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:18:0) after 1m0s
[14:32:53.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:18:0)
[14:32:53.110] Timer tick, asking Buildbucket for the build status
[14:32:54.560] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:19:0) after 1m0s
[14:33:54.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:19:0)
[14:33:54.604] Timer tick, asking Buildbucket for the build status
[14:33:54.859] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:54.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:20:0) after 1m0s
[14:34:55.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:20:0)
[14:34:55.015] Timer tick, asking Buildbucket for the build status
[14:34:55.112] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:55.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:21:0) after 1m0s
[14:35:55.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:21:0)
[14:35:55.168] Timer tick, asking Buildbucket for the build status
[14:35:55.304] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:55.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:22:0) after 1m0s
[14:36:55.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:22:0)
[14:36:55.425] Timer tick, asking Buildbucket for the build status
[14:36:55.567] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:55.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:23:0) after 1m0s
[14:37:55.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:23:0)
[14:37:55.608] Timer tick, asking Buildbucket for the build status
[14:37:55.683] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:55.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:24:0) after 1m0s
[14:38:55.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:24:0)
[14:38:55.735] Timer tick, asking Buildbucket for the build status
[14:38:55.886] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:55.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:25:0) after 1m0s
[14:39:55.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:25:0)
[14:39:55.934] Timer tick, asking Buildbucket for the build status
[14:39:56.041] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:56.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:26:0) after 1m0s
[14:40:56.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:26:0)
[14:40:56.085] Timer tick, asking Buildbucket for the build status
[14:40:56.187] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:27:0) after 1m0s
[14:41:56.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:27:0)
[14:41:56.198] Timer tick, asking Buildbucket for the build status
[14:41:57.219] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:28:0) after 1m0s
[14:42:57.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:28:0)
[14:42:57.187] Timer tick, asking Buildbucket for the build status
[14:42:57.266] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:57.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:29:0) after 1m0s
[14:43:57.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:29:0)
[14:43:57.294] Timer tick, asking Buildbucket for the build status
[14:43:57.401] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:57.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:30:0) after 1m0s
[14:44:57.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:30:0)
[14:44:57.424] Timer tick, asking Buildbucket for the build status
[14:44:57.830] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:57.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:31:0) after 1m0s
[14:45:57.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:31:0)
[14:45:57.861] Timer tick, asking Buildbucket for the build status
[14:45:57.926] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:57.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:32:0) after 1m0s
[14:46:57.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:32:0)
[14:46:57.943] Timer tick, asking Buildbucket for the build status
[14:46:58.034] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:58.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:33:0) after 1m0s
[14:47:58.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:33:0)
[14:47:58.075] Timer tick, asking Buildbucket for the build status
[14:47:58.305] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:34:0) after 1m0s
[14:48:58.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:34:0)
[14:48:58.365] Timer tick, asking Buildbucket for the build status
[14:48:58.441] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:58.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:35:0) after 1m0s
[14:49:58.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:35:0)
[14:49:58.497] Timer tick, asking Buildbucket for the build status
[14:49:58.650] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:58.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:36:0) after 1m0s
[14:50:58.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:36:0)
[14:50:58.668] Timer tick, asking Buildbucket for the build status
[14:50:58.747] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:58.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:37:0) after 1m0s
[14:51:58.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:37:0)
[14:51:58.827] Timer tick, asking Buildbucket for the build status
[14:51:59.202] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:59.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:38:0) after 1m0s
[14:52:59.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:38:0)
[14:52:59.210] Timer tick, asking Buildbucket for the build status
[14:52:59.311] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:59.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:39:0) after 1m0s
[14:53:59.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:39:0)
[14:53:59.423] Timer tick, asking Buildbucket for the build status
[14:53:59.539] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:59.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:40:0) after 1m0s
[14:54:59.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:40:0)
[14:54:59.556] Timer tick, asking Buildbucket for the build status
[14:54:59.695] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:59.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:41:0) after 1m0s
[14:55:59.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:41:0)
[14:55:59.729] Timer tick, asking Buildbucket for the build status
[14:55:59.801] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:59.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:42:0) after 1m0s
[14:56:59.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:42:0)
[14:56:59.850] Timer tick, asking Buildbucket for the build status
[14:56:59.919] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:59.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:43:0) after 1m0s
[14:57:59.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:43:0)
[14:57:59.977] Timer tick, asking Buildbucket for the build status
[14:58:00.411] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:00.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:44:0) after 1m0s
[14:59:00.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:44:0)
[14:59:00.494] Timer tick, asking Buildbucket for the build status
[14:59:04.416] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:04.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:45:0) after 1m0s
[15:00:04.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:45:0)
[15:00:04.446] Timer tick, asking Buildbucket for the build status
[15:00:04.760] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:04.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:46:0) after 1m0s
[15:01:04.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:46:0)
[15:01:04.797] Timer tick, asking Buildbucket for the build status
[15:01:05.105] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:05.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:47:0) after 1m0s
[15:02:05.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:47:0)
[15:02:05.244] Timer tick, asking Buildbucket for the build status
[15:02:05.807] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:05.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:48:0) after 1m0s
[15:03:05.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:48:0)
[15:03:05.827] Timer tick, asking Buildbucket for the build status
[15:03:06.017] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:06.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:49:0) after 1m0s
[15:04:06.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:49:0)
[15:04:06.049] Timer tick, asking Buildbucket for the build status
[15:04:06.250] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:50:0) after 1m0s
[15:05:06.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:50:0)
[15:05:06.258] Timer tick, asking Buildbucket for the build status
[15:05:06.407] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:06.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:51:0) after 1m0s
[15:06:06.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:51:0)
[15:06:06.414] Timer tick, asking Buildbucket for the build status
[15:06:06.499] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:06.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:52:0) after 1m0s
[15:07:06.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:52:0)
[15:07:06.540] Timer tick, asking Buildbucket for the build status
[15:07:06.741] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:06.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:53:0) after 1m0s
[15:08:06.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:53:0)
[15:08:06.859] Timer tick, asking Buildbucket for the build status
[15:08:07.538] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:07.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:54:0) after 1m0s
[15:09:07.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:54:0)
[15:09:07.559] Timer tick, asking Buildbucket for the build status
[15:09:07.734] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:07.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:55:0) after 1m0s
[15:10:09.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:55:0)
[15:10:09.904] Timer tick, asking Buildbucket for the build status
[15:10:10.042] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:10.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:56:0) after 1m0s
[15:11:10.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:56:0)
[15:11:10.133] Timer tick, asking Buildbucket for the build status
[15:11:10.254] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:10.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:57:0) after 1m0s
[15:12:10.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:57:0)
[15:12:10.273] Timer tick, asking Buildbucket for the build status
[15:12:10.607] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:10.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:58:0) after 1m0s
[15:13:10.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:58:0)
[15:13:10.966] Timer tick, asking Buildbucket for the build status
[15:13:11.706] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:11.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:59:0) after 1m0s
[15:14:12.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:59:0)
[15:14:12.088] Timer tick, asking Buildbucket for the build status
[15:14:12.368] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:12.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:60:0) after 1m0s
[15:15:12.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:60:0)
[15:15:12.361] Timer tick, asking Buildbucket for the build status
[15:15:12.449] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:61:0) after 1m0s
[15:16:12.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:61:0)
[15:16:12.520] Timer tick, asking Buildbucket for the build status
[15:16:12.751] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:12.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:62:0) after 1m0s
[15:17:12.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:62:0)
[15:17:12.805] Timer tick, asking Buildbucket for the build status
[15:17:12.932] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:12.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:63:0) after 1m0s
[15:18:12.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:63:0)
[15:18:12.998] Timer tick, asking Buildbucket for the build status
[15:18:13.207] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:13.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:64:0) after 1m0s
[15:19:13.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:64:0)
[15:19:13.467] Timer tick, asking Buildbucket for the build status
[15:19:13.810] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:13.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:65:0) after 1m0s
[15:20:14.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:65:0)
[15:20:14.275] Timer tick, asking Buildbucket for the build status
[15:20:14.463] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:14.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:66:0) after 1m0s
[15:21:14.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:66:0)
[15:21:14.447] Timer tick, asking Buildbucket for the build status
[15:21:14.721] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:14.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:67:0) after 1m0s
[15:22:14.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:67:0)
[15:22:14.755] Timer tick, asking Buildbucket for the build status
[15:22:14.852] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:14.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:68:0) after 1m0s
[15:23:14.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:68:0)
[15:23:14.967] Timer tick, asking Buildbucket for the build status
[15:23:15.055] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:15.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:69:0) after 1m0s
[15:24:15.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:69:0)
[15:24:15.170] Timer tick, asking Buildbucket for the build status
[15:24:15.270] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:15.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:70:0) after 1m0s
[15:25:15.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:70:0)
[15:25:15.300] Timer tick, asking Buildbucket for the build status
[15:25:15.676] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:15.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:71:0) after 1m0s
[15:26:15.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:71:0)
[15:26:15.702] Timer tick, asking Buildbucket for the build status
[15:26:15.830] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:15.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:72:0) after 1m0s
[15:27:15.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:72:0)
[15:27:15.871] Timer tick, asking Buildbucket for the build status
[15:27:17.560] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:17.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:73:0) after 1m0s
[15:28:17.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:73:0)
[15:28:17.549] Timer tick, asking Buildbucket for the build status
[15:28:17.809] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:74:0) after 1m0s
[15:29:17.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:74:0)
[15:29:17.809] Timer tick, asking Buildbucket for the build status
[15:29:17.879] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:75:0) after 1m0s
[15:30:17.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:75:0)
[15:30:17.922] Timer tick, asking Buildbucket for the build status
[15:30:18.367] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:18.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:76:0) after 1m0s
[15:31:18.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:76:0)
[15:31:18.455] Timer tick, asking Buildbucket for the build status
[15:31:18.692] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:18.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:77:0) after 1m0s
[15:32:18.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:77:0)
[15:32:18.797] Timer tick, asking Buildbucket for the build status
[15:32:18.891] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:18.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:78:0) after 1m0s
[15:33:18.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:78:0)
[15:33:18.915] Timer tick, asking Buildbucket for the build status
[15:33:19.233] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:19.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:79:0) after 1m0s
[15:34:19.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:79:0)
[15:34:19.311] Timer tick, asking Buildbucket for the build status
[15:34:19.400] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:19.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:80:0) after 1m0s
[15:35:19.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:80:0)
[15:35:19.504] Timer tick, asking Buildbucket for the build status
[15:35:19.587] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:19.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:81:0) after 1m0s
[15:36:19.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:81:0)
[15:36:19.624] Timer tick, asking Buildbucket for the build status
[15:36:19.709] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:19.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:82:0) after 1m0s
[15:37:19.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:82:0)
[15:37:19.719] Timer tick, asking Buildbucket for the build status
[15:37:19.825] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:19.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:83:0) after 1m0s
[15:38:19.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:83:0)
[15:38:19.845] Timer tick, asking Buildbucket for the build status
[15:38:20.140] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:20.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:84:0) after 1m0s
[15:39:20.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:84:0)
[15:39:20.165] Timer tick, asking Buildbucket for the build status
[15:39:20.280] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:20.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:85:0) after 1m0s
[15:40:20.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:85:0)
[15:40:20.300] Timer tick, asking Buildbucket for the build status
[15:40:20.390] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:20.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:86:0) after 1m0s
[15:41:20.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:86:0)
[15:41:20.396] Timer tick, asking Buildbucket for the build status
[15:41:20.592] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:20.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:87:0) after 1m0s
[15:42:20.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:87:0)
[15:42:20.614] Timer tick, asking Buildbucket for the build status
[15:42:20.711] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:20.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:88:0) after 1m0s
[15:43:20.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:88:0)
[15:43:20.808] Timer tick, asking Buildbucket for the build status
[15:43:21.208] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:21.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:89:0) after 1m0s
[15:44:21.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:89:0)
[15:44:21.257] Timer tick, asking Buildbucket for the build status
[15:44:21.650] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:21.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:90:0) after 1m0s
[15:45:21.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:90:0)
[15:45:21.699] Timer tick, asking Buildbucket for the build status
[15:45:21.968] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:91:0) after 1m0s
[15:46:21.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:91:0)
[15:46:21.988] Timer tick, asking Buildbucket for the build status
[15:46:22.447] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:22.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:92:0) after 1m0s
[15:47:22.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:92:0)
[15:47:22.491] Timer tick, asking Buildbucket for the build status
[15:47:22.594] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:22.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:93:0) after 1m0s
[15:48:22.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:93:0)
[15:48:22.602] Timer tick, asking Buildbucket for the build status
[15:48:22.685] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:22.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:94:0) after 1m0s
[15:49:22.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:94:0)
[15:49:22.706] Timer tick, asking Buildbucket for the build status
[15:49:22.792] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:22.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:95:0) after 1m0s
[15:50:22.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:95:0)
[15:50:22.838] Timer tick, asking Buildbucket for the build status
[15:50:22.949] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:22.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:96:0) after 1m0s
[15:51:22.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:96:0)
[15:51:22.975] Timer tick, asking Buildbucket for the build status
[15:51:23.179] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:23.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:97:0) after 1m0s
[15:52:23.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:97:0)
[15:52:23.233] Timer tick, asking Buildbucket for the build status
[15:52:24.085] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:98:0) after 1m0s
[15:53:24.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:98:0)
[15:53:24.139] Timer tick, asking Buildbucket for the build status
[15:53:24.225] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:24.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:99:0) after 1m0s
[15:54:24.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:99:0)
[15:54:24.217] Timer tick, asking Buildbucket for the build status
[15:54:24.339] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:24.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:100:0) after 1m0s
[15:55:24.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:100:0)
[15:55:24.364] Timer tick, asking Buildbucket for the build status
[15:55:25.725] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:25.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:101:0) after 1m0s
[15:56:25.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:101:0)
[15:56:25.836] Timer tick, asking Buildbucket for the build status
[15:56:25.939] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:25.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:102:0) after 1m0s
[15:57:26.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:102:0)
[15:57:26.094] Timer tick, asking Buildbucket for the build status
[15:57:26.224] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:26.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:103:0) after 1m0s
[15:58:26.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:103:0)
[15:58:26.388] Timer tick, asking Buildbucket for the build status
[15:58:26.653] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:26.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:104:0) after 1m0s
[15:59:26.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:104:0)
[15:59:26.652] Timer tick, asking Buildbucket for the build status
[15:59:26.927] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:26.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:105:0) after 1m0s
[16:00:26.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:105:0)
[16:00:26.951] Timer tick, asking Buildbucket for the build status
[16:00:27.034] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:27.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:106:0) after 1m0s
[16:01:27.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:106:0)
[16:01:27.072] Timer tick, asking Buildbucket for the build status
[16:01:27.484] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:27.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:107:0) after 1m0s
[16:02:27.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:107:0)
[16:02:27.605] Timer tick, asking Buildbucket for the build status
[16:02:27.689] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:27.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:108:0) after 1m0s
[16:03:27.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:108:0)
[16:03:27.810] Timer tick, asking Buildbucket for the build status
[16:03:27.966] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:27.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:109:0) after 1m0s
[16:04:28.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:109:0)
[16:04:28.010] Timer tick, asking Buildbucket for the build status
[16:04:28.287] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:28.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:110:0) after 1m0s
[16:05:28.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:110:0)
[16:05:28.366] Timer tick, asking Buildbucket for the build status
[16:05:28.500] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:111:0) after 1m0s
[16:06:28.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:111:0)
[16:06:28.590] Timer tick, asking Buildbucket for the build status
[16:06:28.749] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:28.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:112:0) after 1m0s
[16:07:28.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:112:0)
[16:07:28.831] Timer tick, asking Buildbucket for the build status
[16:07:28.936] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:28.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:113:0) after 1m0s
[16:08:28.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:113:0)
[16:08:28.965] Timer tick, asking Buildbucket for the build status
[16:08:29.082] Build 8852685943789145040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018116005846693008:114:0) after 1m0s
[16:09:01.175] Received PubSub notification, asking Buildbucket for the build status
[16:09:01.636] Build 8852685943789145040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:09:01.636] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615824540435331",
"created_by": "project:chromium",
"created_ts": "1615817806405793",
"id": "8852685943789145040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cf672362b614bbbdc02b615cfb53568a88a71887\"}}",
"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\": \"cf672362b614bbbdc02b615cfb53568a88a71887\", \"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-10-dcpw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17803, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"45ce8d1e89ea780ed7f87a10e65a6d12c0ca5d33\", \"got_dawn_revision\": \"d41aa4378d25e551e6e2f3ae0406757e994ff24b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"cf672362b614bbbdc02b615cfb53568a88a71887\", \"got_revision_cp\": \"refs/heads/master@{#862801}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"5cdf55ff2084d8aa719fe74fbe1622b61ff64294\", \"got_v8_revision\": \"9becce304a5e9b05bff62eef6c5e53b944120eb2\", \"got_v8_revision_cp\": \"refs/heads/9.1.125@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"is_cached\": true, \"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\": \"cf672362b614bbbdc02b615cfb53568a88a71887\", \"swarm_hashes_refs/heads/master(at){#862801}_without_patch\": {\"absl_hardening_tests\": \"edb3ded17a5fe69b2bef42f1b967d245e4c137b7\", \"accessibility_unittests\": \"c4d9ba95382485f031ef6ca73c51170692411d74\", \"angle_unittests\": \"d5f2d363fb1177eb37f5006999bd23a2925c82af\", \"app_shell_unittests\": \"1ea5b987987334c9d237c16506daaba096fcd522\", \"aura_unittests\": \"c9c5bba7c0b0d5932fb33b61358a5302aa49da79\", \"base_unittests\": \"0b7edd1a6124df58e3bc5c39fbcb04c5176c6209\", \"base_util_unittests\": \"073cfb19a7aa84e6db736fabe25fe9f5b8b291fd\", \"blink_common_unittests\": \"1b38f5b8b22beb5b52e7fe480fa77451c48b4ef0\", \"blink_fuzzer_unittests\": \"254e0d26b936366cdb31181853da5726f47d03d5\", \"blink_heap_unittests\": \"42e347b9c6a25832f637e9850d5f595e49b26e21\", \"blink_platform_unittests\": \"9e3a3aa0d0cb18299991847e2249b97dc72f8014\", \"blink_unittests\": \"5ff80b37eb4a53f9359cc73178fad325c19fc6c7\", \"boringssl_crypto_tests\": \"a9dccf85baed1a43263640b8676ed08040350605\", \"boringssl_ssl_tests\": \"9ef07cbc36f6f14b12775be56be743ee2a1e2dae\", \"browser_switcher_bho_unittests\": \"66dd8d291cd98aa3a8b76b4946b8f01d368badf0\", \"browser_tests\": \"599a96e9d0bfee8d657d28f508e9dea27ca34374\", \"capture_unittests\": \"7f9b1ea0ab771be6f3b5325a764dd2ef965879a2\", \"cast_unittests\": \"56c9c30c064f1495ec3637ca8919fb1034d5ffc8\", \"cc_unittests\": \"33a36a5854509566b08ca7eceb29547913672a66\", \"chrome_app_unittests\": \"061c7189b16c75a194cf10839851e7e198cc6b2a\", \"chrome_cleaner_unittests\": \"3b42fdce7792c0cdfeb2e4d1bec2a859cdf40e90\", \"chrome_elf_unittests\": \"b043c12a3ac50956533c0e17bb92ae5a74331aa4\", \"chromedriver_unittests\": \"dfb8f1ba558fa144dc1e66abdcf3f965d5cd6616\", \"color_unittests\": \"7b7526fc6377e224dd46ed454cfe28805b077360\", \"components_browsertests\": \"6adae00fbbe766d4ee3d155f70d8730495ae4a43\", \"components_unittests\": \"823dddfcb3d935b157d80a819c3dbb14a306f7bf\", \"compositor_unittests\": \"8e867d36d71700042bea21b00e8e061108a525b4\", \"content_browsertests\": \"c7c733865702c147fe95aa1e3d2080cebc79f884\", \"content_unittests\": \"2cde12e51b0d955243312e4d5e86668e1265d109\", \"courgette_unittests\": \"094c8d7aafdb3b7ca1a512b6fba91d1567ac64b5\", \"crashpad_tests\": \"4d01ecfd475662c27d7feaaf99e988d7cbf1e803\", \"cronet_tests\": \"ff675b9cc59dda554e2cc102d22bdf018d9b8638\", \"cronet_unittests\": \"4b855c48274acdb9df1bc04c4b6aa1f4c64d13bb\", \"crypto_unittests\": \"d6a33c9942d400e49f52dff1320e07c8f5b5c0c2\", \"delayloads_unittests\": \"8f482160f6c0ba4edc3ae73a32776538f1811ca6\", \"device_unittests\": \"5bef17d994d01ac4a6216b9a758323f2415a570d\", \"display_unittests\": \"613a38c5191b3217a05523bc3c524b80ad0ff43b\", \"elevation_service_unittests\": \"3daf4de425045af8072da83e0116f96f09ea18de\", \"events_unittests\": \"060849441b24b2d10f3a4d50fa6928805d7402df\", \"extensions_browsertests\": \"93afd8fc3a4ffe5b8a95a37647ba9328a6c06fe0\", \"extensions_unittests\": \"392faaf507d519f2f1c7a5a97be6688ce0fab266\", \"filesystem_service_unittests\": \"012fb0570acee3b7f22cd4c2b17960ea6616db99\", \"gcm_unit_tests\": \"cb03160f3f2f6acc6bf35ada850a2b843efc726d\", \"gcp_unittests\": \"939d5d1da61e422e8e6d2932a1f3354e7be07319\", \"gfx_unittests\": \"2e159d46334c490425cd8ca5ce9bba092a0f7100\", \"gin_unittests\": \"5224cdd18e5e8e2d58c4be834856024a469ae0f3\", \"google_apis_unittests\": \"db57a9830020a482ec29873580c330a9e4289cd5\", \"gpu_unittests\": \"962e8efc972c91da9c749a09da91e129864e4b80\", \"gwp_asan_unittests\": \"604055284bd8bc41ca4ed528fd40a80dcae83032\", \"headless_browsertests\": \"08fdc50a6090e329b7451aea13b159a08052bd06\", \"headless_unittests\": \"ec49b645dc1dfced88f86dc504037b7f2d4d93c2\", \"install_static_unittests\": \"f76019098fb61587ebdff264a51730d90a29176c\", \"installer_util_unittests\": \"52b0364088fa930520bb4323acf18e616c726718\", \"interactive_ui_tests\": \"2bc8bfaadb5d4edd3385c694472e2981c41ce769\", \"ipc_tests\": \"c6227e8ea2d8717dd48efe2aa9917e8af7df1fe9\", \"jingle_unittests\": \"edf924ca7cdd71921d3315fa5e734df1078edb7a\", \"latency_unittests\": \"d938da9001488df465ea95a58e907706e08f4498\", \"libjingle_xmpp_unittests\": \"5ad9691f7dc2806a53cbea365952f0c4be93916f\", \"liburlpattern_unittests\": \"9a282c059da33e36d1c3a84323101717c6776dc1\", \"media_blink_unittests\": \"6fc4248d2d760e182e5772ae1d23f3a644690909\", \"media_unittests\": \"5740a613abd8cacb7f6687975a9dc1ff191f90fd\", \"message_center_unittests\": \"370855596d5b5507ef02e06fa97626a14f7e2df5\", \"midi_unittests\": \"0ac6d2818695c1a9440e910c0815d9eec3381966\", \"mojo_core_unittests\": \"3786b785462d7f71c712013509ea48a67dea89f7\", \"mojo_unittests\": \"f7f0e192674df9ab3c8fd7c4d5fb42cf80e67448\", \"nacl_loader_unittests\": \"80e4335a16aa1380f1b232124de8b06fbd34bd50\", \"native_theme_unittests\": \"a8dff6040e4ebd8b5dfe1bedf49bb0c5ea8f3f34\", \"net_unittests\": \"4395cb10fb9a9e37935765573345fe921c738efc\", \"notification_helper_unittests\": \"f37aa757da3402beb6496fd8e117364ea52437d6\", \"pdf_unittests\": \"135ac26e87704e925f9eeb91724b78ef74a8cb11\", \"perfetto_unittests\": \"e48ba0721a8b2e388f00c20af20cec92fc73265c\", \"ppapi_unittests\": \"1f2770f402bdc1cdf76273e9ddce4be2ff599619\", \"printing_unittests\": \"e64d1690fbbf4475b3d34d2fc8469e400b6bf38e\", \"remoting_unittests\": \"cc9555daa96ddaf570a1cfa6a484906f767a3460\", \"sbox_integration_tests\": \"321a86962e74b666b5052d7a954b834149103d29\", \"sbox_unittests\": \"01fb2aafa7aadde466d0be1c54617e28a62326fa\", \"sbox_validation_tests\": \"d9c282c68a8dff15ecbaca01cd82a31bfaafabab\", \"service_manager_unittests\": \"a407066e7fd01feab6a1d158f24e3387949d162f\", \"services_unittests\": \"1150272f0f0cf6aaf6df42c0a7749e21a7f81b9c\", \"setup_unittests\": \"e5028c3a8a076a35ccd05ebdfa866a953599e197\", \"shell_dialogs_unittests\": \"3b764e12e8705a996541224fe5216b28e3ac0645\", \"skia_unittests\": \"b6fddeaa18f757866f2848cf51f5160befbdc9fe\", \"snapshot_unittests\": \"86cad952b95c524b37f798522a7eed420bdab229\", \"sql_unittests\": \"e006ca5f9c4e5bd481621b98a4d98d2190030dbc\", \"storage_unittests\": \"edcff9bdee5e34f4cc821e3c421ca630df54b9e3\", \"sync_integration_tests\": \"8d93b108660cb26e323467bac9af9853cb239374\", \"traffic_annotation_auditor_unittests\": \"a2f864f33b8b5956609c4eacc1e87cc8cedcf326\", \"ui_base_unittests\": \"371959c042e473b013045e86bb371adc1b305efd\", \"ui_touch_selection_unittests\": \"6139643f52fb19d7972891056e82f6087143304f\", \"unit_tests\": \"880860baa57bde917ef8499ea72a6551a7099185\", \"updater_tests\": \"170a120c2cbf49a493348fe26b0fcc73e924940d\", \"url_unittests\": \"2dbcddc0695f5420a653baf5f2ac23307a1ac0a5\", \"views_unittests\": \"1799943975ebc7b9ccd627cc14db561a718f1eda\", \"viz_unittests\": \"ec1c8f39473c9f56837e55a8d8cfbffdedabc90e\", \"vr_common_unittests\": \"4de5b1d812b9b60d71204ea71d9fd82986af4c3d\", \"vr_pixeltests\": \"193b74e140788f6854812356d87bb7b7e620b9e1\", \"weblayer_browsertests\": \"39875d5c2afff42877671510d65dd4bbf2405168\", \"weblayer_unittests\": \"be013c4eee9a0ac892773dd0e5feba9f274e073a\", \"wm_unittests\": \"b9a29a3a14d9faf0a007bad8e8173f55a91e4df6\", \"wtf_unittests\": \"68b9457eca5dd8c23f9d808fb63348f28ca281db\", \"zlib_unittests\": \"65d65ed6fbb8ee70b80d4e1dae2a2812d94473fe\", \"zucchini_unittests\": \"b3d82d4fc68f312ebb08723c62ae8e32e017fd65\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-dcpw\"], \"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\": [\"5754-8c4f760\"], \"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": "1615818012874109",
"status": "COMPLETED",
"status_changed_ts": "1615824540686129",
"tags": [
"build_address:luci.chromium.ci/win-asan/17803",
"builder:win-asan",
"buildset:commit/git/cf672362b614bbbdc02b615cfb53568a88a71887",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf672362b614bbbdc02b615cfb53568a88a71887",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018116005846693008",
"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/8852685943789145040/+/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:524f10a2412bcf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615824540691088",
"url": "https://ci.chromium.org/b/8852685943789145040",
"utcnow_ts": "1615824541570190"
}
}
[16:09:01.636] Invocation finished in 1h52m16.577269771s with status SUCCEEDED