[13:29:30.909] New invocation is queued and will start shortly
[13:29:32.168] Starting the invocation (attempt 1)
[13:29:32.224] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:29:32.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:29:32.225] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021289871509722336",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8d5e46b207cc3a72728b42aad9aec5d622317000\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021289871509722336",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8d5e46b207cc3a72728b42aad9aec5d622317000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d5e46b207cc3a72728b42aad9aec5d622317000",
"gitiles_ref:refs/heads/master"
]
}
[13:29:32.950] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612790972345275",
"id": "8855859809341039408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d5e46b207cc3a72728b42aad9aec5d622317000\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612790972793325",
"tags": [
"build_address:luci.chromium.ci/win-asan/17240",
"builder:win-asan",
"buildset:commit/git/8d5e46b207cc3a72728b42aad9aec5d622317000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d5e46b207cc3a72728b42aad9aec5d622317000",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021289871509722336",
"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/8855859809341039408/+/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": "1612790972793429",
"url": "https://ci.chromium.org/b/8855859809341039408",
"utcnow_ts": "1612790972938441"
}
}
[13:29:32.950] Task URL: https://ci.chromium.org/b/8855859809341039408
[13:29:32.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:2:0) after 1m0s
[13:30:32.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:2:0)
[13:30:32.978] Timer tick, asking Buildbucket for the build status
[13:30:33.111] Build 8855859809341039408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:33.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:3:0) after 1m0s
[13:31:33.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:3:0)
[13:31:33.149] Timer tick, asking Buildbucket for the build status
[13:31:33.290] Build 8855859809341039408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:33.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:4:0) after 1m0s
[13:32:33.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:4:0)
[13:32:33.269] Timer tick, asking Buildbucket for the build status
[13:32:33.335] Build 8855859809341039408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:33.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:5:0) after 1m0s
[13:33:33.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:5:0)
[13:33:33.384] Timer tick, asking Buildbucket for the build status
[13:33:33.763] Build 8855859809341039408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:33.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:6:0) after 1m0s
[13:33:47.727] Received PubSub notification, asking Buildbucket for the build status
[13:33:48.028] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:33.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:6:0)
[13:34:33.817] Timer tick, asking Buildbucket for the build status
[13:34:33.886] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:33.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:8:0) after 1m0s
[13:35:33.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:8:0)
[13:35:33.971] Timer tick, asking Buildbucket for the build status
[13:35:34.069] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:9:0) after 1m0s
[13:36:34.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:9:0)
[13:36:34.185] Timer tick, asking Buildbucket for the build status
[13:36:34.250] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:34.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:10:0) after 1m0s
[13:37:34.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:10:0)
[13:37:34.364] Timer tick, asking Buildbucket for the build status
[13:37:34.452] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:34.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:11:0) after 1m0s
[13:38:34.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:11:0)
[13:38:34.505] Timer tick, asking Buildbucket for the build status
[13:38:34.612] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:34.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:12:0) after 1m0s
[13:39:34.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:12:0)
[13:39:34.711] Timer tick, asking Buildbucket for the build status
[13:39:34.851] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:13:0) after 1m0s
[13:40:34.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:13:0)
[13:40:34.958] Timer tick, asking Buildbucket for the build status
[13:40:35.178] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:35.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:14:0) after 1m0s
[13:41:35.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:14:0)
[13:41:35.294] Timer tick, asking Buildbucket for the build status
[13:41:35.392] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:35.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:15:0) after 1m0s
[13:42:35.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:15:0)
[13:42:35.431] Timer tick, asking Buildbucket for the build status
[13:42:35.492] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:35.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:16:0) after 1m0s
[13:43:35.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:16:0)
[13:43:35.691] Timer tick, asking Buildbucket for the build status
[13:43:35.761] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:35.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:17:0) after 1m0s
[13:44:35.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:17:0)
[13:44:35.782] Timer tick, asking Buildbucket for the build status
[13:44:36.108] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:36.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:18:0) after 1m0s
[13:45:36.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:18:0)
[13:45:36.156] Timer tick, asking Buildbucket for the build status
[13:45:36.258] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:36.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:19:0) after 1m0s
[13:46:37.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:19:0)
[13:46:37.420] Timer tick, asking Buildbucket for the build status
[13:46:38.054] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:38.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:20:0) after 1m0s
[13:47:38.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:20:0)
[13:47:38.386] Timer tick, asking Buildbucket for the build status
[13:47:38.528] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:38.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:21:0) after 1m0s
[13:48:38.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:21:0)
[13:48:38.685] Timer tick, asking Buildbucket for the build status
[13:48:38.976] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:38.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:22:0) after 1m0s
[13:49:39.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:22:0)
[13:49:39.012] Timer tick, asking Buildbucket for the build status
[13:49:40.020] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:40.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:23:0) after 1m0s
[13:50:40.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:23:0)
[13:50:40.233] Timer tick, asking Buildbucket for the build status
[13:50:40.303] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:40.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:24:0) after 1m0s
[13:51:40.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:24:0)
[13:51:40.393] Timer tick, asking Buildbucket for the build status
[13:51:40.475] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:40.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:25:0) after 1m0s
[13:52:40.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:25:0)
[13:52:40.615] Timer tick, asking Buildbucket for the build status
[13:52:40.857] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:40.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:26:0) after 1m0s
[13:53:40.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:26:0)
[13:53:40.912] Timer tick, asking Buildbucket for the build status
[13:53:40.964] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:40.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:27:0) after 1m0s
[13:54:41.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:27:0)
[13:54:41.043] Timer tick, asking Buildbucket for the build status
[13:54:41.180] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:41.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:28:0) after 1m0s
[13:55:41.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:28:0)
[13:55:41.233] Timer tick, asking Buildbucket for the build status
[13:55:41.294] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:41.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:29:0) after 1m0s
[13:56:41.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:29:0)
[13:56:41.338] Timer tick, asking Buildbucket for the build status
[13:56:41.396] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:41.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:30:0) after 1m0s
[13:57:41.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:30:0)
[13:57:41.411] Timer tick, asking Buildbucket for the build status
[13:57:41.611] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:41.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:31:0) after 1m0s
[13:58:41.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:31:0)
[13:58:41.657] Timer tick, asking Buildbucket for the build status
[13:58:41.802] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:41.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:32:0) after 1m0s
[13:59:41.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:32:0)
[13:59:41.827] Timer tick, asking Buildbucket for the build status
[13:59:42.075] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:42.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:33:0) after 1m0s
[14:00:42.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:33:0)
[14:00:42.137] Timer tick, asking Buildbucket for the build status
[14:00:42.208] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:42.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:34:0) after 1m0s
[14:01:42.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:34:0)
[14:01:42.261] Timer tick, asking Buildbucket for the build status
[14:01:42.326] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:42.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:35:0) after 1m0s
[14:02:42.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:35:0)
[14:02:42.359] Timer tick, asking Buildbucket for the build status
[14:02:42.423] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:42.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:36:0) after 1m0s
[14:03:42.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:36:0)
[14:03:42.496] Timer tick, asking Buildbucket for the build status
[14:03:42.703] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:42.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:37:0) after 1m0s
[14:04:42.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:37:0)
[14:04:42.805] Timer tick, asking Buildbucket for the build status
[14:04:43.204] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:43.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:38:0) after 1m0s
[14:05:43.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:38:0)
[14:05:43.326] Timer tick, asking Buildbucket for the build status
[14:05:43.975] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:43.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:39:0) after 1m0s
[14:06:44.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:39:0)
[14:06:44.002] Timer tick, asking Buildbucket for the build status
[14:06:44.233] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:44.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:40:0) after 1m0s
[14:07:44.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:40:0)
[14:07:44.254] Timer tick, asking Buildbucket for the build status
[14:07:44.345] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:44.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:41:0) after 1m0s
[14:08:44.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:41:0)
[14:08:44.369] Timer tick, asking Buildbucket for the build status
[14:08:44.457] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:44.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:42:0) after 1m0s
[14:09:44.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:42:0)
[14:09:44.507] Timer tick, asking Buildbucket for the build status
[14:09:44.810] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:44.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:43:0) after 1m0s
[14:10:46.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:43:0)
[14:10:46.299] Timer tick, asking Buildbucket for the build status
[14:10:46.491] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:46.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:44:0) after 1m0s
[14:11:46.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:44:0)
[14:11:46.703] Timer tick, asking Buildbucket for the build status
[14:11:46.797] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:46.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:45:0) after 1m0s
[14:12:46.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:45:0)
[14:12:46.978] Timer tick, asking Buildbucket for the build status
[14:12:47.057] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:46:0) after 1m0s
[14:13:47.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:46:0)
[14:13:47.149] Timer tick, asking Buildbucket for the build status
[14:13:47.426] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:47.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:47:0) after 1m0s
[14:14:47.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:47:0)
[14:14:47.478] Timer tick, asking Buildbucket for the build status
[14:14:47.589] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:47.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:48:0) after 1m0s
[14:15:47.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:48:0)
[14:15:47.641] Timer tick, asking Buildbucket for the build status
[14:15:47.930] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:49:0) after 1m0s
[14:16:48.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:49:0)
[14:16:48.028] Timer tick, asking Buildbucket for the build status
[14:16:48.129] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:48.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:50:0) after 1m0s
[14:17:48.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:50:0)
[14:17:48.224] Timer tick, asking Buildbucket for the build status
[14:17:48.312] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:48.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:51:0) after 1m0s
[14:18:48.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:51:0)
[14:18:48.305] Timer tick, asking Buildbucket for the build status
[14:18:48.358] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:52:0) after 1m0s
[14:19:48.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:52:0)
[14:19:48.443] Timer tick, asking Buildbucket for the build status
[14:19:48.579] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:48.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:53:0) after 1m0s
[14:20:48.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:53:0)
[14:20:48.601] Timer tick, asking Buildbucket for the build status
[14:20:48.726] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:54:0) after 1m0s
[14:21:48.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:54:0)
[14:21:48.746] Timer tick, asking Buildbucket for the build status
[14:21:48.845] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:55:0) after 1m0s
[14:22:49.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:55:0)
[14:22:49.112] Timer tick, asking Buildbucket for the build status
[14:22:49.187] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:56:0) after 1m0s
[14:23:49.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:56:0)
[14:23:49.209] Timer tick, asking Buildbucket for the build status
[14:23:49.269] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:49.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:57:0) after 1m0s
[14:24:49.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:57:0)
[14:24:49.296] Timer tick, asking Buildbucket for the build status
[14:24:49.525] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:49.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:58:0) after 1m0s
[14:25:49.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:58:0)
[14:25:49.545] Timer tick, asking Buildbucket for the build status
[14:25:50.786] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:50.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:59:0) after 1m0s
[14:26:50.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:59:0)
[14:26:50.808] Timer tick, asking Buildbucket for the build status
[14:26:50.987] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:60:0) after 1m0s
[14:27:51.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:60:0)
[14:27:51.072] Timer tick, asking Buildbucket for the build status
[14:27:51.150] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:51.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:61:0) after 1m0s
[14:28:51.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:61:0)
[14:28:51.196] Timer tick, asking Buildbucket for the build status
[14:28:51.276] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:51.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:62:0) after 1m0s
[14:29:52.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:62:0)
[14:29:52.679] Timer tick, asking Buildbucket for the build status
[14:29:52.935] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:52.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:63:0) after 1m0s
[14:30:53.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:63:0)
[14:30:53.180] Timer tick, asking Buildbucket for the build status
[14:30:53.358] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:53.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:64:0) after 1m0s
[14:31:53.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:64:0)
[14:31:53.505] Timer tick, asking Buildbucket for the build status
[14:31:53.606] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:53.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:65:0) after 1m0s
[14:32:53.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:65:0)
[14:32:53.681] Timer tick, asking Buildbucket for the build status
[14:32:53.735] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:53.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:66:0) after 1m0s
[14:33:53.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:66:0)
[14:33:53.843] Timer tick, asking Buildbucket for the build status
[14:33:53.906] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:53.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:67:0) after 1m0s
[14:34:54.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:67:0)
[14:34:54.044] Timer tick, asking Buildbucket for the build status
[14:34:54.230] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:68:0) after 1m0s
[14:35:54.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:68:0)
[14:35:54.348] Timer tick, asking Buildbucket for the build status
[14:35:54.500] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:54.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:69:0) after 1m0s
[14:36:54.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:69:0)
[14:36:54.626] Timer tick, asking Buildbucket for the build status
[14:36:57.890] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:70:0) after 1m0s
[14:37:58.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:70:0)
[14:37:58.044] Timer tick, asking Buildbucket for the build status
[14:37:58.326] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:58.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:71:0) after 1m0s
[14:38:58.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:71:0)
[14:38:58.399] Timer tick, asking Buildbucket for the build status
[14:38:58.551] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:58.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:72:0) after 1m0s
[14:39:58.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:72:0)
[14:39:58.645] Timer tick, asking Buildbucket for the build status
[14:39:58.754] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:73:0) after 1m0s
[14:40:58.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:73:0)
[14:40:58.841] Timer tick, asking Buildbucket for the build status
[14:40:58.956] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:58.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:74:0) after 1m0s
[14:41:59.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:74:0)
[14:41:59.027] Timer tick, asking Buildbucket for the build status
[14:41:59.729] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:75:0) after 1m0s
[14:42:59.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:75:0)
[14:42:59.787] Timer tick, asking Buildbucket for the build status
[14:42:59.903] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:59.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:76:0) after 1m0s
[14:43:59.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:76:0)
[14:43:59.984] Timer tick, asking Buildbucket for the build status
[14:44:00.406] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:00.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:77:0) after 1m0s
[14:45:00.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:77:0)
[14:45:00.461] Timer tick, asking Buildbucket for the build status
[14:45:00.576] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:00.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:78:0) after 1m0s
[14:46:00.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:78:0)
[14:46:00.647] Timer tick, asking Buildbucket for the build status
[14:46:00.979] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:00.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:79:0) after 1m0s
[14:47:01.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:79:0)
[14:47:01.158] Timer tick, asking Buildbucket for the build status
[14:47:01.265] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:01.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:80:0) after 1m0s
[14:48:01.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:80:0)
[14:48:01.463] Timer tick, asking Buildbucket for the build status
[14:48:01.660] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:81:0) after 1m0s
[14:49:02.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:81:0)
[14:49:02.132] Timer tick, asking Buildbucket for the build status
[14:49:02.206] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:02.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:82:0) after 1m0s
[14:50:02.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:82:0)
[14:50:02.205] Timer tick, asking Buildbucket for the build status
[14:50:02.371] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:83:0) after 1m0s
[14:51:02.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:83:0)
[14:51:02.575] Timer tick, asking Buildbucket for the build status
[14:51:02.793] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:84:0) after 1m0s
[14:52:02.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:84:0)
[14:52:02.892] Timer tick, asking Buildbucket for the build status
[14:52:03.406] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:03.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:85:0) after 1m0s
[14:53:03.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:85:0)
[14:53:03.546] Timer tick, asking Buildbucket for the build status
[14:53:03.832] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:03.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:86:0) after 1m0s
[14:54:03.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:86:0)
[14:54:03.854] Timer tick, asking Buildbucket for the build status
[14:54:03.924] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:03.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:87:0) after 1m0s
[14:55:03.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:87:0)
[14:55:03.970] Timer tick, asking Buildbucket for the build status
[14:55:04.472] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:04.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:88:0) after 1m0s
[14:56:04.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:88:0)
[14:56:04.504] Timer tick, asking Buildbucket for the build status
[14:56:04.585] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:04.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:89:0) after 1m0s
[14:57:04.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:89:0)
[14:57:04.612] Timer tick, asking Buildbucket for the build status
[14:57:04.688] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:04.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:90:0) after 1m0s
[14:58:04.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:90:0)
[14:58:04.822] Timer tick, asking Buildbucket for the build status
[14:58:04.946] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:04.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:91:0) after 1m0s
[14:59:04.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:91:0)
[14:59:04.951] Timer tick, asking Buildbucket for the build status
[14:59:05.042] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:05.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:92:0) after 1m0s
[15:00:05.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:92:0)
[15:00:05.097] Timer tick, asking Buildbucket for the build status
[15:00:05.456] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:05.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:93:0) after 1m0s
[15:01:06.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:93:0)
[15:01:06.007] Timer tick, asking Buildbucket for the build status
[15:01:08.205] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:08.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:94:0) after 1m0s
[15:02:08.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:94:0)
[15:02:08.307] Timer tick, asking Buildbucket for the build status
[15:02:08.456] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:08.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:95:0) after 1m0s
[15:03:08.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:95:0)
[15:03:08.559] Timer tick, asking Buildbucket for the build status
[15:03:08.740] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:08.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:96:0) after 1m0s
[15:04:08.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:96:0)
[15:04:08.806] Timer tick, asking Buildbucket for the build status
[15:04:08.979] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:08.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:97:0) after 1m0s
[15:05:09.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:97:0)
[15:05:09.091] Timer tick, asking Buildbucket for the build status
[15:05:09.577] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:09.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:98:0) after 1m0s
[15:06:09.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:98:0)
[15:06:09.643] Timer tick, asking Buildbucket for the build status
[15:06:09.751] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:09.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:99:0) after 1m0s
[15:07:09.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:99:0)
[15:07:09.794] Timer tick, asking Buildbucket for the build status
[15:07:09.860] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:09.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:100:0) after 1m0s
[15:08:09.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:100:0)
[15:08:09.892] Timer tick, asking Buildbucket for the build status
[15:08:10.366] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:101:0) after 1m0s
[15:09:10.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:101:0)
[15:09:10.416] Timer tick, asking Buildbucket for the build status
[15:09:10.580] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:10.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:102:0) after 1m0s
[15:10:10.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:102:0)
[15:10:10.697] Timer tick, asking Buildbucket for the build status
[15:10:10.791] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:10.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:103:0) after 1m0s
[15:11:10.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:103:0)
[15:11:10.830] Timer tick, asking Buildbucket for the build status
[15:11:11.504] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:11.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:104:0) after 1m0s
[15:12:11.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:104:0)
[15:12:11.534] Timer tick, asking Buildbucket for the build status
[15:12:11.788] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:11.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:105:0) after 1m0s
[15:13:11.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:105:0)
[15:13:11.858] Timer tick, asking Buildbucket for the build status
[15:13:11.950] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:11.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:106:0) after 1m0s
[15:14:12.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:106:0)
[15:14:12.025] Timer tick, asking Buildbucket for the build status
[15:14:12.166] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:12.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:107:0) after 1m0s
[15:15:12.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:107:0)
[15:15:12.189] Timer tick, asking Buildbucket for the build status
[15:15:12.316] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:12.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:108:0) after 1m0s
[15:16:12.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:108:0)
[15:16:12.376] Timer tick, asking Buildbucket for the build status
[15:16:12.482] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:12.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:109:0) after 1m0s
[15:17:12.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:109:0)
[15:17:12.461] Timer tick, asking Buildbucket for the build status
[15:17:12.551] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:12.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:110:0) after 1m0s
[15:18:12.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:110:0)
[15:18:12.721] Timer tick, asking Buildbucket for the build status
[15:18:12.805] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:12.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:111:0) after 1m0s
[15:19:12.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:111:0)
[15:19:12.978] Timer tick, asking Buildbucket for the build status
[15:19:13.059] Build 8855859809341039408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:13.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021289871509722336:112:0) after 1m0s
[15:20:06.603] Received PubSub notification, asking Buildbucket for the build status
[15:20:06.749] Build 8855859809341039408: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:20:06.749] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612797602659607",
"created_by": "project:chromium",
"created_ts": "1612790972345275",
"failure_reason": "BUILD_FAILURE",
"id": "8855859809341039408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d5e46b207cc3a72728b42aad9aec5d622317000\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PasswordAutofillAgentTest.WaitUsername\\n\\n- PasswordGenerationAgentTest.JavascriptClearedTheField\\n\\n- PasswordAutofillAgentTest.PasswordNotClearedOnEdit\\n\\n- PasswordAutofillAgentTest.SendPasswordFormsTest_PasswordChangeForm\\n\\n- ...8 more failure(s) (12 total)...\"}, \"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\": \"8d5e46b207cc3a72728b42aad9aec5d622317000\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17240, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"5712107a35b053c2e4ffb3a5690e6e482a0c84f5\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"8d5e46b207cc3a72728b42aad9aec5d622317000\", \"got_revision_cp\": \"refs/heads/master@{#851686}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"0b911e791ef3aefc64d94c7c36b35f010acd7dc0\", \"got_v8_revision_cp\": \"refs/heads/9.0.139@{#1}\", \"got_webrtc_revision\": \"a0848ddeff6fb7768afdebd5fdfdedf92858f292\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33187}\", \"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\": \"8d5e46b207cc3a72728b42aad9aec5d622317000\", \"swarm_hashes_refs/heads/master(at){#851686}_without_patch\": {\"absl_hardening_tests\": \"2ee035c2f4f0bce441944f69de5869a6becec1de\", \"accessibility_unittests\": \"0a6ecf6d138dedd8e18fb172a44e25680f2534e2\", \"angle_unittests\": \"d91f4db0c6222757d107429c6c0456768997fcc3\", \"app_shell_unittests\": \"ae21d76db8e90ba6b65e09bf48a47974703c4590\", \"aura_unittests\": \"22d5865f12e95167b4ce058168f95ea9e1e13374\", \"base_unittests\": \"d0f05f8cbb65a87ada517c8427f53376f5596939\", \"base_util_unittests\": \"5a36a330bda5aafe92de0f6b65501054a7102668\", \"blink_common_unittests\": \"1ad1d38e60982c7adb771099011d100a817bd6b1\", \"blink_fuzzer_unittests\": \"d1188789c202a989ca8934995f007a1229a08435\", \"blink_heap_unittests\": \"de989b58c547f44211fc4fb4ebf6dbcae6bd2f59\", \"blink_platform_unittests\": \"cc31a86497a09dd3cb6319212a64f2e220e2da06\", \"blink_unittests\": \"08c4745aec2e5f98b2c9b7e7f20b771f89017db1\", \"boringssl_crypto_tests\": \"d8d186eecb15cf3e060aaefe249573980b578e54\", \"boringssl_ssl_tests\": \"5c01ae6e20204b5fcda1b4cbce03840a64318054\", \"browser_switcher_bho_unittests\": \"981a398bfb625a45699c310fbc840ac604237c72\", \"browser_tests\": \"45a110d796e9b927fb1cfe66d4f333c690985f79\", \"capture_unittests\": \"c68bc4f4f22705734ea9759582e86f9022b80212\", \"cast_unittests\": \"3f3533c9b8b3ec3be8f8e9a57098a0474adaf50d\", \"cc_unittests\": \"f1ee13c2a812e9ad780affce924fc2629b9ae59e\", \"chrome_app_unittests\": \"6260e735bccdb6965f2d7b3f4587bd90d3b936b3\", \"chrome_cleaner_unittests\": \"bbae02038aaad1beea425aca9da6617d33f2fc54\", \"chrome_elf_unittests\": \"a85cd64f5e7246840340314489d4ba12dc5e9c38\", \"chromedriver_unittests\": \"8096c69d5f5f07e75707596fff69b7e4ecb684d0\", \"components_browsertests\": \"776604eed9de6d5b3bcf14e3a7e23901dc2595cc\", \"components_unittests\": \"cc64befe6e14b5a2e6613a0b0c3cb0f89d13df1c\", \"compositor_unittests\": \"ee73870bdbd53515f640d1f3a0c0507e58b985a5\", \"content_browsertests\": \"06c2ceb34b0caa75f0423c728e6ea6754fef48a2\", \"content_unittests\": \"a6da43a171461fd2a7a6a9a29ac6b73579fcd709\", \"courgette_unittests\": \"b6d06c80262427e8df748c27621efd5fa3421360\", \"crashpad_tests\": \"314436c6ae333f642569f89b770f6d1da41dd2bc\", \"cronet_tests\": \"3db0a28aec9ee0640f2f7702c48e7abc2c8ba436\", \"cronet_unittests\": \"7cfa160ca3d8e82c2cc7506ddba1619b2e0ccf20\", \"crypto_unittests\": \"38d6491158df60567cc6967450cfc842aa3032a1\", \"delayloads_unittests\": \"5e3f18d5dfd48107df74af525adb4c03a1ad3aaf\", \"device_unittests\": \"5b1c90915a1cd51f34b28fbe8a13c39d5dbb981a\", \"display_unittests\": \"774959feee695b688c10fa092591c2f9e2a36c87\", \"elevation_service_unittests\": \"838243af60a371be3b884bb262028e597410bed1\", \"events_unittests\": \"1fb7272ca8e763327e923bbceabd29fea37ab4a2\", \"extensions_browsertests\": \"550a001d792a5ef93b6f6730a353d924f1853ee3\", \"extensions_unittests\": \"5056096113b5cf29e1c9d5165f37c9fd8eaf88da\", \"filesystem_service_unittests\": \"d035e3c988a7b6d330172aa3939a34e2bc1b83bf\", \"gcm_unit_tests\": \"a6b8ebce91a4f309488c8ecf4e6d16811c0c35f2\", \"gcp_unittests\": \"871df89d9684cb25d8592d73dc3021f76d78786c\", \"gfx_unittests\": \"8f552f61d5d1f0771084f9d7ee2a30634da6e6aa\", \"gin_unittests\": \"b3c4c49f6e6a27bf8c9a8ddc23f6b7a58e90eefc\", \"google_apis_unittests\": \"848de9f41b27cfb3a3ce53f03c691dda2540feb7\", \"gpu_unittests\": \"78c18356782132449e48dad22acb120fb533c676\", \"gwp_asan_unittests\": \"7ae147177c3878852fb2ac7cb8ac483b90ad1b77\", \"headless_browsertests\": \"fb1855aca17c52ed42a872260f8ad5e845eb13e6\", \"headless_unittests\": \"3c61b1a2805321a0292949351f1aea1b234c9b1f\", \"install_static_unittests\": \"445e38f9674408efa93e4888cea62d825bebd220\", \"installer_util_unittests\": \"c2493abade7540e7a37c6a87ee87068f7557dbdb\", \"interactive_ui_tests\": \"695b2c542e78acb7928ca246ee11c3f13f5d6f54\", \"ipc_tests\": \"3a1e46344caf8bd27afc7356f39dd541f8bb6302\", \"jingle_unittests\": \"1ffbaf560dcfa50547b8fe841710daec6dfffa3b\", \"latency_unittests\": \"2433afa431067dd629d372f3ae166add1ec3e3ae\", \"libjingle_xmpp_unittests\": \"4605967812ef5d4b5c71aef5507940e1a3efb5a8\", \"liburlpattern_unittests\": \"8e516e1e6fbc8a50d35670b3fbc9e8bfd1e3e4c8\", \"media_blink_unittests\": \"d3b389a37c8142645a489cfe732bebf0de1d5e35\", \"media_unittests\": \"6eb9f6bf18e6065fe4c7242a8065ad43c2c2fa6b\", \"message_center_unittests\": \"4fe605aa585836a8c8611e1c4154687fa74c8891\", \"midi_unittests\": \"87b1a7b52634ef1402bfaee535f380854ee3c65f\", \"mojo_core_unittests\": \"6675d287de7c9a2b3e98b0a4e6ab768ea7d2dc25\", \"mojo_unittests\": \"beebb299f566ad1eb3e1e76593fe264cf5c4aeba\", \"nacl_loader_unittests\": \"64ecdfb92e9f7c729a1b38830127e655756a54d9\", \"native_theme_unittests\": \"d15c6dcd0db710283c4c9ba3cb0cf270e34ef07a\", \"net_unittests\": \"a72a61980495cce7ec3e4c287cde8274f42496f4\", \"notification_helper_unittests\": \"94796fa6fbd4e2dad91beb62c71cbdb309cd929d\", \"pdf_unittests\": \"4156a68482fa3480047cf5e29d0dab5deddd8da6\", \"perfetto_unittests\": \"05292087a70e9fbdd9991a303f41a659e6f30be8\", \"ppapi_unittests\": \"2495504377b23157faa2279e0094a92e46f89636\", \"printing_unittests\": \"4a8c02136d679138aeb9489785e41cce24e7d7e5\", \"remoting_unittests\": \"a9d038c898939b83b2e8f5fc512c3dca335f45f4\", \"sbox_integration_tests\": \"4ac4ce154be4039f6560bcb60896790dbc1e34cb\", \"sbox_unittests\": \"532b2954292243d837f6bc67c301c2c0a6e45be7\", \"sbox_validation_tests\": \"bed75d90d33e46b328437cbbfbeab24f41f99afa\", \"service_manager_unittests\": \"1aa58e1e9647af757171348e00574883657f1e32\", \"services_unittests\": \"3ee5676038459f52b29cf7fce41929401cc15cb8\", \"setup_unittests\": \"3d0300bf08dcfc8fe13f284bb5e9098121e01367\", \"shell_dialogs_unittests\": \"10a5ac6983ebc7a02247044ee72e24f8ee8ce7c8\", \"skia_unittests\": \"3782eba8726a1fbf28248a17fb088d6ced4504cc\", \"snapshot_unittests\": \"5a0981a4bb42dbf3364196a401750d4511dce137\", \"sql_unittests\": \"fad224aa32ef7c1d7f199601ad74961d0cd081df\", \"storage_unittests\": \"1d661700d768d0530cb805e87a63da0c222f52a2\", \"sync_integration_tests\": \"12e817c87ad4c0cdffdb7d757158a4bb87d17ef5\", \"traffic_annotation_auditor_unittests\": \"1e6689c2702171100e61c4af1cc17d31aed0746e\", \"ui_base_unittests\": \"47ef6cf2cb0d39cff21e6bae866195345745cb3f\", \"ui_touch_selection_unittests\": \"bc4d3bfb0099254a562db0e84d7ae731170192fe\", \"unit_tests\": \"fa3e72fd52a3cb642d261d5fe6518318847b50ba\", \"updater_tests\": \"4d89e786638a65ba58bdc04c0b1ddce5dd73292e\", \"url_unittests\": \"f741e1084d5b37d06a84608113f2fd773d5f4d43\", \"views_unittests\": \"68e0814024b7bec5d0b8a61acdc16351fe17805c\", \"viz_unittests\": \"1e6318a6f4cf1abbca2690228c7e0dde4121762f\", \"vr_common_unittests\": \"a765c20fd7020bcad72276a86bbd7d5b055fbbd3\", \"vr_pixeltests\": \"39423d044a0df9af1e5d753a22b95c4cf7f5e2e1\", \"weblayer_browsertests\": \"e2c67a4775363a1a74ff31a6b99438538bb3d3bd\", \"weblayer_unittests\": \"eee6f1868c35dd2f86cfb7395f5c724c87807f06\", \"wm_unittests\": \"f08820296289ca84e788fcf713068a7f235fb007\", \"wtf_unittests\": \"2ba999a0cfe3f8191a97681027177ffc15e218e1\", \"zlib_unittests\": \"eb3acedf1dd2262c9b426ab9126561e768997d1e\", \"zucchini_unittests\": \"57e03a9adbb90c55aa31c738dcf15b01a651083b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"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-7-1hzs\"], \"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\": [\"5681-01e7fd1\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PasswordAutofillAgentTest.WaitUsername\\n\\n- PasswordGenerationAgentTest.JavascriptClearedTheField\\n\\n- PasswordAutofillAgentTest.PasswordNotClearedOnEdit\\n\\n- PasswordAutofillAgentTest.SendPasswordFormsTest_PasswordChangeForm\\n\\n- ...8 more failure(s) (12 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612791227254148",
"status": "COMPLETED",
"status_changed_ts": "1612791227539205",
"tags": [
"build_address:luci.chromium.ci/win-asan/17240",
"builder:win-asan",
"buildset:commit/git/8d5e46b207cc3a72728b42aad9aec5d622317000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d5e46b207cc3a72728b42aad9aec5d622317000",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021289871509722336",
"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/8855859809341039408/+/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:519aa6d084cb9810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612797605954192",
"url": "https://ci.chromium.org/b/8855859809341039408",
"utcnow_ts": "1612797606736452"
}
}
[15:20:06.749] Invocation finished in 1h50m35.855747747s with status FAILED