[18:57:18.163] New invocation is queued and will start shortly
[18:57:19.409] Starting the invocation (attempt 1)
[18:57:19.447] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:57:19.447] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:57:19.447] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021088054969265504",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021088054969265504",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"gitiles_ref:refs/heads/master"
]
}
[18:57:20.830] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612983439615958",
"id": "8855657992780308272",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612983440538757",
"tags": [
"build_address:luci.chromium.ci/win-asan/17274",
"builder:win-asan",
"buildset:commit/git/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021088054969265504",
"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/8855657992780308272/+/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": "1612983440538909",
"url": "https://ci.chromium.org/b/8855657992780308272",
"utcnow_ts": "1612983440822467"
}
}
[18:57:20.830] Task URL: https://ci.chromium.org/b/8855657992780308272
[18:57:20.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:2:0) after 1m0s
[18:58:10.391] Received PubSub notification, asking Buildbucket for the build status
[18:58:10.958] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:20.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:2:0)
[18:58:20.924] Timer tick, asking Buildbucket for the build status
[18:58:21.330] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:21.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:4:0) after 1m0s
[18:59:21.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:4:0)
[18:59:21.381] Timer tick, asking Buildbucket for the build status
[18:59:21.664] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:21.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:5:0) after 1m0s
[19:00:21.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:5:0)
[19:00:21.688] Timer tick, asking Buildbucket for the build status
[19:00:22.072] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:22.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:6:0) after 1m0s
[19:01:22.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:6:0)
[19:01:22.073] Timer tick, asking Buildbucket for the build status
[19:01:24.732] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:24.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:7:0) after 1m0s
[19:02:24.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:7:0)
[19:02:24.783] Timer tick, asking Buildbucket for the build status
[19:02:24.951] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:24.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:8:0) after 1m0s
[19:03:24.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:8:0)
[19:03:24.954] Timer tick, asking Buildbucket for the build status
[19:03:25.028] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:25.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:9:0) after 1m0s
[19:04:25.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:9:0)
[19:04:25.015] Timer tick, asking Buildbucket for the build status
[19:04:25.302] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:25.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:10:0) after 1m0s
[19:05:25.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:10:0)
[19:05:25.324] Timer tick, asking Buildbucket for the build status
[19:05:25.514] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:25.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:11:0) after 1m0s
[19:06:25.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:11:0)
[19:06:25.630] Timer tick, asking Buildbucket for the build status
[19:06:25.913] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:25.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:12:0) after 1m0s
[19:07:25.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:12:0)
[19:07:25.940] Timer tick, asking Buildbucket for the build status
[19:07:26.645] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:26.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:13:0) after 1m0s
[19:08:26.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:13:0)
[19:08:26.763] Timer tick, asking Buildbucket for the build status
[19:08:27.183] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:27.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:14:0) after 1m0s
[19:09:28.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:14:0)
[19:09:28.299] Timer tick, asking Buildbucket for the build status
[19:09:28.507] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:28.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:15:0) after 1m0s
[19:10:29.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:15:0)
[19:10:29.468] Timer tick, asking Buildbucket for the build status
[19:10:29.734] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:29.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:16:0) after 1m0s
[19:11:30.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:16:0)
[19:11:30.006] Timer tick, asking Buildbucket for the build status
[19:11:30.552] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:17:0) after 1m0s
[19:12:31.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:17:0)
[19:12:31.304] Timer tick, asking Buildbucket for the build status
[19:12:31.509] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:31.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:18:0) after 1m0s
[19:13:31.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:18:0)
[19:13:31.969] Timer tick, asking Buildbucket for the build status
[19:13:32.336] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:32.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:19:0) after 1m0s
[19:14:32.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:19:0)
[19:14:32.658] Timer tick, asking Buildbucket for the build status
[19:14:32.910] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:32.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:20:0) after 1m0s
[19:15:33.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:20:0)
[19:15:33.296] Timer tick, asking Buildbucket for the build status
[19:15:33.459] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:33.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:21:0) after 1m0s
[19:16:33.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:21:0)
[19:16:33.866] Timer tick, asking Buildbucket for the build status
[19:16:33.994] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:33.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:22:0) after 1m0s
[19:17:34.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:22:0)
[19:17:34.175] Timer tick, asking Buildbucket for the build status
[19:17:34.514] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:34.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:23:0) after 1m0s
[19:18:34.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:23:0)
[19:18:34.612] Timer tick, asking Buildbucket for the build status
[19:18:37.708] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:37.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:24:0) after 1m0s
[19:19:38.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:24:0)
[19:19:38.555] Timer tick, asking Buildbucket for the build status
[19:19:38.640] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:38.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:25:0) after 1m0s
[19:20:38.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:25:0)
[19:20:38.803] Timer tick, asking Buildbucket for the build status
[19:20:38.882] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:38.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:26:0) after 1m0s
[19:21:38.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:26:0)
[19:21:38.916] Timer tick, asking Buildbucket for the build status
[19:21:39.047] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:27:0) after 1m0s
[19:22:39.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:27:0)
[19:22:39.202] Timer tick, asking Buildbucket for the build status
[19:22:39.391] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:39.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:28:0) after 1m0s
[19:23:39.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:28:0)
[19:23:39.422] Timer tick, asking Buildbucket for the build status
[19:23:39.971] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:39.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:29:0) after 1m0s
[19:24:40.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:29:0)
[19:24:40.023] Timer tick, asking Buildbucket for the build status
[19:24:40.108] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:40.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:30:0) after 1m0s
[19:25:40.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:30:0)
[19:25:40.173] Timer tick, asking Buildbucket for the build status
[19:25:40.256] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:40.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:31:0) after 1m0s
[19:26:40.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:31:0)
[19:26:40.286] Timer tick, asking Buildbucket for the build status
[19:26:40.348] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:40.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:32:0) after 1m0s
[19:27:40.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:32:0)
[19:27:40.408] Timer tick, asking Buildbucket for the build status
[19:27:40.819] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:40.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:33:0) after 1m0s
[19:28:40.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:33:0)
[19:28:40.921] Timer tick, asking Buildbucket for the build status
[19:28:41.055] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:41.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:34:0) after 1m0s
[19:29:41.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:34:0)
[19:29:41.073] Timer tick, asking Buildbucket for the build status
[19:29:41.136] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:41.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:35:0) after 1m0s
[19:30:41.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:35:0)
[19:30:41.251] Timer tick, asking Buildbucket for the build status
[19:30:41.345] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:41.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:36:0) after 1m0s
[19:31:42.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:36:0)
[19:31:42.568] Timer tick, asking Buildbucket for the build status
[19:31:42.650] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:42.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:37:0) after 1m0s
[19:32:42.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:37:0)
[19:32:42.756] Timer tick, asking Buildbucket for the build status
[19:32:42.849] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:42.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:38:0) after 1m0s
[19:33:42.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:38:0)
[19:33:42.849] Timer tick, asking Buildbucket for the build status
[19:33:42.953] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:42.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:39:0) after 1m0s
[19:34:43.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:39:0)
[19:34:43.013] Timer tick, asking Buildbucket for the build status
[19:34:43.388] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:43.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:40:0) after 1m0s
[19:35:43.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:40:0)
[19:35:43.542] Timer tick, asking Buildbucket for the build status
[19:35:43.639] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:43.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:41:0) after 1m0s
[19:36:43.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:41:0)
[19:36:43.718] Timer tick, asking Buildbucket for the build status
[19:36:43.794] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:42:0) after 1m0s
[19:37:45.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:42:0)
[19:37:45.532] Timer tick, asking Buildbucket for the build status
[19:37:45.841] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:45.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:43:0) after 1m0s
[19:38:46.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:43:0)
[19:38:46.329] Timer tick, asking Buildbucket for the build status
[19:38:46.961] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:46.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:44:0) after 1m0s
[19:39:47.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:44:0)
[19:39:47.111] Timer tick, asking Buildbucket for the build status
[19:39:47.381] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:47.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:45:0) after 1m0s
[19:40:49.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:45:0)
[19:40:49.026] Timer tick, asking Buildbucket for the build status
[19:40:49.126] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:49.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:46:0) after 1m0s
[19:41:49.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:46:0)
[19:41:49.172] Timer tick, asking Buildbucket for the build status
[19:41:49.248] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:49.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:47:0) after 1m0s
[19:42:49.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:47:0)
[19:42:49.290] Timer tick, asking Buildbucket for the build status
[19:42:49.497] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:48:0) after 1m0s
[19:43:49.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:48:0)
[19:43:49.804] Timer tick, asking Buildbucket for the build status
[19:43:49.880] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:49.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:49:0) after 1m0s
[19:44:49.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:49:0)
[19:44:49.900] Timer tick, asking Buildbucket for the build status
[19:44:49.977] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:49.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:50:0) after 1m0s
[19:45:50.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:50:0)
[19:45:50.027] Timer tick, asking Buildbucket for the build status
[19:45:50.114] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:50.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:51:0) after 1m0s
[19:46:50.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:51:0)
[19:46:50.135] Timer tick, asking Buildbucket for the build status
[19:46:50.331] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:50.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:52:0) after 1m0s
[19:47:50.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:52:0)
[19:47:50.320] Timer tick, asking Buildbucket for the build status
[19:47:50.410] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:50.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:53:0) after 1m0s
[19:48:51.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:53:0)
[19:48:51.158] Timer tick, asking Buildbucket for the build status
[19:48:51.324] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:51.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:54:0) after 1m0s
[19:49:51.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:54:0)
[19:49:51.613] Timer tick, asking Buildbucket for the build status
[19:49:51.897] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:51.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:55:0) after 1m0s
[19:50:51.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:55:0)
[19:50:51.990] Timer tick, asking Buildbucket for the build status
[19:50:52.063] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:52.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:56:0) after 1m0s
[19:51:52.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:56:0)
[19:51:52.176] Timer tick, asking Buildbucket for the build status
[19:51:52.319] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:52.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:57:0) after 1m0s
[19:52:54.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:57:0)
[19:52:54.206] Timer tick, asking Buildbucket for the build status
[19:52:54.519] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:54.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:58:0) after 1m0s
[19:53:54.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:58:0)
[19:53:54.552] Timer tick, asking Buildbucket for the build status
[19:53:54.659] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:54.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:59:0) after 1m0s
[19:54:54.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:59:0)
[19:54:54.723] Timer tick, asking Buildbucket for the build status
[19:54:54.859] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:54.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:60:0) after 1m0s
[19:55:54.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:60:0)
[19:55:54.850] Timer tick, asking Buildbucket for the build status
[19:55:55.022] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:55.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:61:0) after 1m0s
[19:56:55.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:61:0)
[19:56:55.077] Timer tick, asking Buildbucket for the build status
[19:56:55.356] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:55.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:62:0) after 1m0s
[19:57:55.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:62:0)
[19:57:55.390] Timer tick, asking Buildbucket for the build status
[19:57:55.480] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:55.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:63:0) after 1m0s
[19:58:55.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:63:0)
[19:58:55.493] Timer tick, asking Buildbucket for the build status
[19:58:55.642] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:55.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:64:0) after 1m0s
[19:59:55.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:64:0)
[19:59:55.669] Timer tick, asking Buildbucket for the build status
[19:59:55.762] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:55.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:65:0) after 1m0s
[20:00:55.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:65:0)
[20:00:55.783] Timer tick, asking Buildbucket for the build status
[20:00:55.878] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:55.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:66:0) after 1m0s
[20:01:55.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:66:0)
[20:01:55.995] Timer tick, asking Buildbucket for the build status
[20:01:56.299] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:56.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:67:0) after 1m0s
[20:02:56.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:67:0)
[20:02:56.411] Timer tick, asking Buildbucket for the build status
[20:02:56.551] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:56.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:68:0) after 1m0s
[20:03:56.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:68:0)
[20:03:56.635] Timer tick, asking Buildbucket for the build status
[20:03:56.765] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:56.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:69:0) after 1m0s
[20:04:56.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:69:0)
[20:04:56.824] Timer tick, asking Buildbucket for the build status
[20:04:56.911] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:56.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:70:0) after 1m0s
[20:05:56.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:70:0)
[20:05:56.984] Timer tick, asking Buildbucket for the build status
[20:05:57.143] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:57.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:71:0) after 1m0s
[20:06:57.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:71:0)
[20:06:57.205] Timer tick, asking Buildbucket for the build status
[20:06:57.305] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:57.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:72:0) after 1m0s
[20:07:57.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:72:0)
[20:07:57.423] Timer tick, asking Buildbucket for the build status
[20:07:57.674] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:57.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:73:0) after 1m0s
[20:08:57.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:73:0)
[20:08:57.767] Timer tick, asking Buildbucket for the build status
[20:08:58.501] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:58.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:74:0) after 1m0s
[20:09:58.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:74:0)
[20:09:58.526] Timer tick, asking Buildbucket for the build status
[20:09:58.620] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:58.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:75:0) after 1m0s
[20:10:58.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:75:0)
[20:10:58.696] Timer tick, asking Buildbucket for the build status
[20:10:58.845] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:58.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:76:0) after 1m0s
[20:11:58.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:76:0)
[20:11:58.880] Timer tick, asking Buildbucket for the build status
[20:11:59.255] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:59.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:77:0) after 1m0s
[20:12:59.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:77:0)
[20:12:59.358] Timer tick, asking Buildbucket for the build status
[20:12:59.610] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:59.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:78:0) after 1m0s
[20:13:59.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:78:0)
[20:13:59.849] Timer tick, asking Buildbucket for the build status
[20:14:00.251] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:00.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:79:0) after 1m0s
[20:15:00.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:79:0)
[20:15:00.280] Timer tick, asking Buildbucket for the build status
[20:15:00.399] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:00.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:80:0) after 1m0s
[20:16:00.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:80:0)
[20:16:00.541] Timer tick, asking Buildbucket for the build status
[20:16:00.697] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:00.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:81:0) after 1m0s
[20:17:01.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:81:0)
[20:17:01.333] Timer tick, asking Buildbucket for the build status
[20:17:01.411] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:01.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:82:0) after 1m0s
[20:18:01.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:82:0)
[20:18:01.674] Timer tick, asking Buildbucket for the build status
[20:18:02.123] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:02.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:83:0) after 1m0s
[20:19:02.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:83:0)
[20:19:02.153] Timer tick, asking Buildbucket for the build status
[20:19:02.250] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:02.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:84:0) after 1m0s
[20:20:02.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:84:0)
[20:20:02.356] Timer tick, asking Buildbucket for the build status
[20:20:02.502] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:02.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:85:0) after 1m0s
[20:21:02.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:85:0)
[20:21:02.633] Timer tick, asking Buildbucket for the build status
[20:21:02.837] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:86:0) after 1m0s
[20:22:03.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:86:0)
[20:22:03.009] Timer tick, asking Buildbucket for the build status
[20:22:03.139] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:03.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:87:0) after 1m0s
[20:23:03.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:87:0)
[20:23:03.267] Timer tick, asking Buildbucket for the build status
[20:23:03.370] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:03.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:88:0) after 1m0s
[20:24:03.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:88:0)
[20:24:03.558] Timer tick, asking Buildbucket for the build status
[20:24:03.672] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:03.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:89:0) after 1m0s
[20:25:03.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:89:0)
[20:25:03.768] Timer tick, asking Buildbucket for the build status
[20:25:04.098] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:90:0) after 1m0s
[20:26:04.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:90:0)
[20:26:04.155] Timer tick, asking Buildbucket for the build status
[20:26:04.740] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:04.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:91:0) after 1m0s
[20:27:04.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:91:0)
[20:27:04.769] Timer tick, asking Buildbucket for the build status
[20:27:04.873] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:04.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:92:0) after 1m0s
[20:28:04.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:92:0)
[20:28:04.964] Timer tick, asking Buildbucket for the build status
[20:28:05.559] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:05.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:93:0) after 1m0s
[20:29:05.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:93:0)
[20:29:05.620] Timer tick, asking Buildbucket for the build status
[20:29:05.720] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:05.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:94:0) after 1m0s
[20:30:05.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:94:0)
[20:30:05.870] Timer tick, asking Buildbucket for the build status
[20:30:05.969] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:05.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:95:0) after 1m0s
[20:31:06.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:95:0)
[20:31:06.209] Timer tick, asking Buildbucket for the build status
[20:31:07.974] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:07.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:96:0) after 1m0s
[20:32:08.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:96:0)
[20:32:08.009] Timer tick, asking Buildbucket for the build status
[20:32:08.368] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:08.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:97:0) after 1m0s
[20:33:08.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:97:0)
[20:33:08.404] Timer tick, asking Buildbucket for the build status
[20:33:08.540] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:08.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:98:0) after 1m0s
[20:34:08.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:98:0)
[20:34:08.603] Timer tick, asking Buildbucket for the build status
[20:34:08.684] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:08.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:99:0) after 1m0s
[20:35:08.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:99:0)
[20:35:08.786] Timer tick, asking Buildbucket for the build status
[20:35:08.871] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:08.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:100:0) after 1m0s
[20:36:09.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:100:0)
[20:36:09.049] Timer tick, asking Buildbucket for the build status
[20:36:09.479] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:09.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:101:0) after 1m0s
[20:37:09.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:101:0)
[20:37:09.566] Timer tick, asking Buildbucket for the build status
[20:37:09.840] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:09.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:102:0) after 1m0s
[20:38:10.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:102:0)
[20:38:10.030] Timer tick, asking Buildbucket for the build status
[20:38:10.158] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:10.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:103:0) after 1m0s
[20:39:10.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:103:0)
[20:39:10.294] Timer tick, asking Buildbucket for the build status
[20:39:10.412] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:10.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:104:0) after 1m0s
[20:40:10.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:104:0)
[20:40:10.425] Timer tick, asking Buildbucket for the build status
[20:40:10.519] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:10.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:105:0) after 1m0s
[20:41:10.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:105:0)
[20:41:10.625] Timer tick, asking Buildbucket for the build status
[20:41:10.911] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:10.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:106:0) after 1m0s
[20:42:11.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:106:0)
[20:42:11.029] Timer tick, asking Buildbucket for the build status
[20:42:11.193] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:11.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:107:0) after 1m0s
[20:43:11.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:107:0)
[20:43:11.232] Timer tick, asking Buildbucket for the build status
[20:43:11.325] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:11.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:108:0) after 1m0s
[20:44:11.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:108:0)
[20:44:11.427] Timer tick, asking Buildbucket for the build status
[20:44:11.594] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:11.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:109:0) after 1m0s
[20:45:11.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:109:0)
[20:45:11.624] Timer tick, asking Buildbucket for the build status
[20:45:11.733] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:11.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:110:0) after 1m0s
[20:46:11.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:110:0)
[20:46:11.756] Timer tick, asking Buildbucket for the build status
[20:46:12.349] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:12.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:111:0) after 1m0s
[20:47:12.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:111:0)
[20:47:12.416] Timer tick, asking Buildbucket for the build status
[20:47:12.620] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:12.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:112:0) after 1m0s
[20:48:12.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:112:0)
[20:48:12.649] Timer tick, asking Buildbucket for the build status
[20:48:12.820] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:12.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:113:0) after 1m0s
[20:49:12.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:113:0)
[20:49:12.923] Timer tick, asking Buildbucket for the build status
[20:49:13.024] Build 8855657992780308272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:13.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021088054969265504:114:0) after 1m0s
[20:49:14.530] Received PubSub notification, asking Buildbucket for the build status
[20:49:14.868] Build 8855657992780308272: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:49:14.868] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612990152982129",
"created_by": "project:chromium",
"created_ts": "1612983439615958",
"failure_reason": "BUILD_FAILURE",
"id": "8855657992780308272",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- AutoscrollBrowserTest.AutoscrollDirectionChangeAfterFullyScrolled\"}, \"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\": \"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-02em\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17274, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"0f74ae58ed185108ec38a4cb038ef3c31acd9ff4\", \"got_dawn_revision\": \"e0a588752c827daef74960bbc80d7f5c4f73c4dd\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\", \"got_revision_cp\": \"refs/heads/master@{#852730}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"ddadcacd13fae633df256fb967e61d4dbf1c8083\", \"got_v8_revision_cp\": \"refs/heads/9.0.157@{#1}\", \"got_webrtc_revision\": \"fa5ad8c0b5efb1b3ad90d8a726feb5cb221a04fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33213}\", \"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\": \"5e1439f7508b2e3d88768f4b9c226f34a57d4c25\", \"swarm_hashes_refs/heads/master(at){#852730}_without_patch\": {\"absl_hardening_tests\": \"0022317bfa9cfa557997d39b0141c67cdc970d2a\", \"accessibility_unittests\": \"f4b3c9369f935ea3ee93cdc0591047958365eb95\", \"angle_unittests\": \"6c28095130f807d4f29223f3616651049dd2a2a3\", \"app_shell_unittests\": \"0f9d9d0378c913a2ce3e9c20045acbd23b559fad\", \"aura_unittests\": \"94a1491d0ffe9089208b3368554267d33c9688ab\", \"base_unittests\": \"d90c9ce6c2b6fb20c385d59d09b5f222d81028ba\", \"base_util_unittests\": \"3bf2c98170642f68aff4d1d7a61fb4e5b2b3a6eb\", \"blink_common_unittests\": \"cdfd1335564cf3db6e6de099dd951798027d5f79\", \"blink_fuzzer_unittests\": \"146c888f3a85d05b6992c609bb9bd13b1a977557\", \"blink_heap_unittests\": \"49eaa074d48ec22d24946832bcb9156e82b12c0c\", \"blink_platform_unittests\": \"fc095134be35350b14fe4cb77c36fef9a79b0f66\", \"blink_unittests\": \"6fe88c9f5d3a85c54eea977dbd814b47ed12a08d\", \"boringssl_crypto_tests\": \"ccc46f932e78626e02dc17d58ea2ee663643bb3a\", \"boringssl_ssl_tests\": \"d2e1f1e9929e3ddeb137571264d68704cca536fe\", \"browser_switcher_bho_unittests\": \"b5a8da9815a0a7bf66278206df0900eecdd1bfef\", \"browser_tests\": \"61bf89919b4fdafd9135d4890e4800b3458a6b86\", \"capture_unittests\": \"eafcd5b4aaf86beafc47545ca3c9b79f40771be7\", \"cast_unittests\": \"698c18c28be16140bd53097c44ea7000608bbad2\", \"cc_unittests\": \"ef27881fa38862ae21ad48a00b10953439b5ed2a\", \"chrome_app_unittests\": \"db6233136e7f721853d3ec291d85330532a80bb1\", \"chrome_cleaner_unittests\": \"0552db6a52aaf3fbf53639573b1a61ca82e38978\", \"chrome_elf_unittests\": \"5fd9a4290f67e79c720340f6939ef4bf4a2f78c0\", \"chromedriver_unittests\": \"ea56e4b18df7ac844436534450691fb5dbf678a5\", \"components_browsertests\": \"173d33de2789d254cc69d6b32841a9dcb75b99a9\", \"components_unittests\": \"638386d52e6dd03c73c8290d83ea503d530be545\", \"compositor_unittests\": \"416d27f357df1054be3a54bd98159cb82f829ec5\", \"content_browsertests\": \"fa10ab25ef30290101c5cfe1109b52eec23cf1a7\", \"content_unittests\": \"7c904231667ec37d8c74a262859c138515fc243f\", \"courgette_unittests\": \"8673cbddf06614f2db0be74170e98bd534eb4961\", \"crashpad_tests\": \"811cf0f9133684a048482b16764758371413ab86\", \"cronet_tests\": \"6d61a61aafb0271f5b58476be223e4a6fc374c5c\", \"cronet_unittests\": \"f963442c1fa30b1f9bde7f4cdd01b96e40c7dca5\", \"crypto_unittests\": \"076294dad0a23bc52d707050be737f21005e8830\", \"delayloads_unittests\": \"f027a57ecd3600d836cd5fa5a5a4308158f5ec46\", \"device_unittests\": \"c8c13ead394af27a4b9ff8596a173083365e223b\", \"display_unittests\": \"2d4ec5c18efc5f1881ae7be9e0446c34625a2c98\", \"elevation_service_unittests\": \"d5c2fef4da095e8759efdaf191bf7fd2ea52efcf\", \"events_unittests\": \"f6e4f20e3726c10f5367ebd89e85f634e102299d\", \"extensions_browsertests\": \"27d1c01d551790dc07f5e3bdefaec85f3b26df62\", \"extensions_unittests\": \"275dfeed8d41ad94be83057c2f466f92faaeabf8\", \"filesystem_service_unittests\": \"6f15fc87589d1be077759d24293a85a631023da9\", \"gcm_unit_tests\": \"1c045f07e7da1dff46e239fd3e96fee94e334322\", \"gcp_unittests\": \"737a3d24097cf65ac00e4342956639e0ffae8db7\", \"gfx_unittests\": \"b05dfa22baf2c211cba2fb3734dc851fa2502088\", \"gin_unittests\": \"c22f4fc3f97acc43e7e5eab983e5969621b589e1\", \"google_apis_unittests\": \"0c411d8f3b519eed9940d11c30b97a164d48867e\", \"gpu_unittests\": \"55fc39e40fcf2d4fb51d0794b2e3e0cd52883c33\", \"gwp_asan_unittests\": \"f290aa132224bff4da5823e1b97b9820d22f8bd1\", \"headless_browsertests\": \"41a732df826b4e1e1f051d7664834220d3fff4b5\", \"headless_unittests\": \"9771e813bdf360cd26fc463c3ab7335955487a9d\", \"install_static_unittests\": \"948e3d8bb879ceb37ae68b37b7b3f80f3f3506dd\", \"installer_util_unittests\": \"9a8fd74c0c0d12fcafee0c965368abf1ac733b73\", \"interactive_ui_tests\": \"a477194b32097f61b707df06ccffd9d4e68ca219\", \"ipc_tests\": \"3645a7223bd9e3a317e34efbe033ba9efcf02b4b\", \"jingle_unittests\": \"5d147f62448b5ca93a6b9a324a6f4cc3f0dc029b\", \"latency_unittests\": \"a5119b1219cf0d0ecd566b602d6fa9713a69cf2c\", \"libjingle_xmpp_unittests\": \"a699c9684f2e189b3fb1472b1da51108f1c194ff\", \"liburlpattern_unittests\": \"dece0a45483c1dbd9504b7e60eb50627abd5f599\", \"media_blink_unittests\": \"da27fcd5a137f0f684786f1ed73a146ed9e7171f\", \"media_unittests\": \"345e652b9424ca3c8f2544ac251be8889fc6ff07\", \"message_center_unittests\": \"f6de60d760f53fb8061f5c3c21ef0394ec26558e\", \"midi_unittests\": \"1a142a26aab4003ba06b4862f3e279bc19d37301\", \"mojo_core_unittests\": \"0cb57cec8dac050c1e11b39a759ae024de37355b\", \"mojo_unittests\": \"1d9ab5919c06e703cb9df16fe552a9e954ab803c\", \"nacl_loader_unittests\": \"1e70a8b602f50d4dfb8f5af8906886b52e210e75\", \"native_theme_unittests\": \"3177d25473775e8f1a5a1c8b7cc9813ae7f2dd05\", \"net_unittests\": \"d3c22493beea182178f4bcce162470fa9497311c\", \"notification_helper_unittests\": \"c4909f1789d3c9284c532b201f3a9d71e5b5e3dc\", \"pdf_unittests\": \"a0dd6a3bd46828ee6437f35846b074a563c830a0\", \"perfetto_unittests\": \"b888154c21ad82d914b866beb9ee1f69e3d8daa1\", \"ppapi_unittests\": \"0db190a42b602d9445ce49b904ccf66d228717d3\", \"printing_unittests\": \"621cf4e58c829242995b711bc0ac26372d2bb923\", \"remoting_unittests\": \"681f2627fc29920fd4defff8002eeb00eed70e59\", \"sbox_integration_tests\": \"1d7af4490e02cdc993d8867f60df92ecd49de7b0\", \"sbox_unittests\": \"876c63e4c517c3dcb01cf5162f9c8a8cb6cfac5e\", \"sbox_validation_tests\": \"743a3daa20c58c8b6e1647a80674a60a530cdb76\", \"service_manager_unittests\": \"85cf67322997f61196f7bf61722c249860ba3a5a\", \"services_unittests\": \"28cfa34f619c12284b8282070c1ec84343f42d6f\", \"setup_unittests\": \"0d3e9ce6523d27a444e4686b5cfb35e31af5c3aa\", \"shell_dialogs_unittests\": \"3e800b218ecea7ce5d873bc742a6d04d67526ae1\", \"skia_unittests\": \"913297b6085b1e9b9b3642ed34174e046dd662d7\", \"snapshot_unittests\": \"9d8df0e47b488770ca160d77aee3da7f5a1c49f0\", \"sql_unittests\": \"9b23c47db6e4647858e771219170ab439586388d\", \"storage_unittests\": \"257648d966750ff0e0bd6bfb5b73449b0d8e9d27\", \"sync_integration_tests\": \"4cac546f8dd643cd2629ed3f2c914e5f4a47db5e\", \"traffic_annotation_auditor_unittests\": \"9df413f6b91ff7da002eb3d7d393cb86d5b08e54\", \"ui_base_unittests\": \"a8dcfd12a864004d60d94ce35db6c9985f0369bb\", \"ui_touch_selection_unittests\": \"d773bd53c06dda8e88bcff75539bdab44c7baf2e\", \"unit_tests\": \"18d7be628b1c44327dd2011fd93b5a418ad31306\", \"updater_tests\": \"be1f1779091d6f8426e172f3d4cd5cabe63559fc\", \"url_unittests\": \"82b8d8c9127d42d837a0bc3620330b0001fc044f\", \"views_unittests\": \"324423c8db5285c0fb111bff0b8d2d4b0777b21b\", \"viz_unittests\": \"7734ddbe3d7a3af2b0b035a7aa026dd5ecf82b46\", \"vr_common_unittests\": \"6fcfd769d7000506b319d334b06819b450738b1e\", \"vr_pixeltests\": \"3fbce819e4d1da7591a57a1e318a276b5ec5be3e\", \"weblayer_browsertests\": \"2fc78828634f076baae332283dedf80e525c6834\", \"weblayer_unittests\": \"674e7649ef9fb0ce319b28f637e411ebdb28ed66\", \"wm_unittests\": \"815f3db24889abc5a2c55840e2ac75f702ef263c\", \"wtf_unittests\": \"394becb8ca939faa8d8db3fd02b4046975d249be\", \"zlib_unittests\": \"60e74e4343f7da66ce7f2c86be5a7f1fe1fb24d3\", \"zucchini_unittests\": \"e9486e550200b5ae7ac4ade1923085c1052af627\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-02em\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- AutoscrollBrowserTest.AutoscrollDirectionChangeAfterFullyScrolled\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612983487180746",
"status": "COMPLETED",
"status_changed_ts": "1612983489789532",
"tags": [
"build_address:luci.chromium.ci/win-asan/17274",
"builder:win-asan",
"buildset:commit/git/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e1439f7508b2e3d88768f4b9c226f34a57d4c25",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021088054969265504",
"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/8855657992780308272/+/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:51a61f9f5bf4e710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612990153368494",
"url": "https://ci.chromium.org/b/8855657992780308272",
"utcnow_ts": "1612990154846625"
}
}
[20:49:14.868] Invocation finished in 1h51m56.7241588s with status FAILED