[16:19:56.406] New invocation is queued and will start shortly
[16:19:57.483] Starting the invocation (attempt 1)
[16:19:57.527] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:19:57.527] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:19:57.527] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024359446155950976",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5bdc25c1b9fee531d294e89378767a789fbad570\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024359446155950976",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5bdc25c1b9fee531d294e89378767a789fbad570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bdc25c1b9fee531d294e89378767a789fbad570",
"gitiles_ref:refs/heads/master"
]
}
[16:19:58.163] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609863597601487",
"id": "8858929384240425744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5bdc25c1b9fee531d294e89378767a789fbad570\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609863597977131",
"tags": [
"build_address:luci.chromium.ci/win-asan/16641",
"builder:win-asan",
"buildset:commit/git/5bdc25c1b9fee531d294e89378767a789fbad570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bdc25c1b9fee531d294e89378767a789fbad570",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024359446155950976",
"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/8858929384240425744/+/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": "1609863597977237",
"url": "https://ci.chromium.org/b/8858929384240425744",
"utcnow_ts": "1609863598153375"
}
}
[16:19:58.163] Task URL: https://ci.chromium.org/b/8858929384240425744
[16:19:58.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:2:0) after 1m0s
[16:20:58.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:2:0)
[16:20:58.213] Timer tick, asking Buildbucket for the build status
[16:20:58.341] Build 8858929384240425744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:58.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:3:0) after 1m0s
[16:21:58.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:3:0)
[16:21:58.341] Timer tick, asking Buildbucket for the build status
[16:21:58.416] Build 8858929384240425744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:58.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:4:0) after 1m0s
[16:22:58.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:4:0)
[16:22:58.401] Timer tick, asking Buildbucket for the build status
[16:22:58.478] Build 8858929384240425744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:58.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:5:0) after 1m0s
[16:23:58.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:5:0)
[16:23:58.507] Timer tick, asking Buildbucket for the build status
[16:23:58.602] Build 8858929384240425744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:58.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:6:0) after 1m0s
[16:24:30.890] Received PubSub notification, asking Buildbucket for the build status
[16:24:30.948] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:58.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:6:0)
[16:24:58.646] Timer tick, asking Buildbucket for the build status
[16:24:58.712] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:58.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:8:0) after 1m0s
[16:25:59.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:8:0)
[16:25:59.432] Timer tick, asking Buildbucket for the build status
[16:25:59.526] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:59.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:9:0) after 1m0s
[16:26:59.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:9:0)
[16:26:59.698] Timer tick, asking Buildbucket for the build status
[16:26:59.810] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:59.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:10:0) after 1m0s
[16:28:00.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:10:0)
[16:28:00.548] Timer tick, asking Buildbucket for the build status
[16:28:00.643] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:00.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:11:0) after 1m0s
[16:29:00.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:11:0)
[16:29:00.979] Timer tick, asking Buildbucket for the build status
[16:29:01.075] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:01.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:12:0) after 1m0s
[16:30:01.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:12:0)
[16:30:01.937] Timer tick, asking Buildbucket for the build status
[16:30:02.065] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:02.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:13:0) after 1m0s
[16:31:02.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:13:0)
[16:31:02.506] Timer tick, asking Buildbucket for the build status
[16:31:02.596] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:02.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:14:0) after 1m0s
[16:32:02.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:14:0)
[16:32:02.874] Timer tick, asking Buildbucket for the build status
[16:32:03.133] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:03.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:15:0) after 1m0s
[16:33:03.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:15:0)
[16:33:03.738] Timer tick, asking Buildbucket for the build status
[16:33:03.833] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:03.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:16:0) after 1m0s
[16:34:03.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:16:0)
[16:34:03.941] Timer tick, asking Buildbucket for the build status
[16:34:04.206] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:04.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:17:0) after 1m0s
[16:35:04.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:17:0)
[16:35:04.302] Timer tick, asking Buildbucket for the build status
[16:35:04.542] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:04.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:18:0) after 1m0s
[16:36:04.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:18:0)
[16:36:04.642] Timer tick, asking Buildbucket for the build status
[16:36:04.892] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:04.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:19:0) after 1m0s
[16:37:05.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:19:0)
[16:37:05.032] Timer tick, asking Buildbucket for the build status
[16:37:05.235] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:05.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:20:0) after 1m0s
[16:38:05.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:20:0)
[16:38:05.368] Timer tick, asking Buildbucket for the build status
[16:38:05.446] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:05.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:21:0) after 1m0s
[16:39:05.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:21:0)
[16:39:05.610] Timer tick, asking Buildbucket for the build status
[16:39:05.679] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:05.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:22:0) after 1m0s
[16:40:05.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:22:0)
[16:40:05.874] Timer tick, asking Buildbucket for the build status
[16:40:06.051] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:06.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:23:0) after 1m0s
[16:41:06.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:23:0)
[16:41:06.666] Timer tick, asking Buildbucket for the build status
[16:41:06.759] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:06.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:24:0) after 1m0s
[16:42:06.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:24:0)
[16:42:06.794] Timer tick, asking Buildbucket for the build status
[16:42:07.090] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:07.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:25:0) after 1m0s
[16:43:07.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:25:0)
[16:43:07.252] Timer tick, asking Buildbucket for the build status
[16:43:07.384] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:07.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:26:0) after 1m0s
[16:44:07.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:26:0)
[16:44:07.583] Timer tick, asking Buildbucket for the build status
[16:44:07.751] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:07.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:27:0) after 1m0s
[16:45:07.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:27:0)
[16:45:07.850] Timer tick, asking Buildbucket for the build status
[16:45:08.109] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:08.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:28:0) after 1m0s
[16:46:08.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:28:0)
[16:46:08.190] Timer tick, asking Buildbucket for the build status
[16:46:08.273] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:08.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:29:0) after 1m0s
[16:47:08.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:29:0)
[16:47:08.281] Timer tick, asking Buildbucket for the build status
[16:47:08.837] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:08.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:30:0) after 1m0s
[16:48:08.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:30:0)
[16:48:08.845] Timer tick, asking Buildbucket for the build status
[16:48:08.971] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:08.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:31:0) after 1m0s
[16:49:09.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:31:0)
[16:49:09.077] Timer tick, asking Buildbucket for the build status
[16:49:09.252] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:09.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:32:0) after 1m0s
[16:50:09.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:32:0)
[16:50:09.293] Timer tick, asking Buildbucket for the build status
[16:50:09.399] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:09.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:33:0) after 1m0s
[16:51:09.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:33:0)
[16:51:09.489] Timer tick, asking Buildbucket for the build status
[16:51:09.564] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:09.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:34:0) after 1m0s
[16:52:09.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:34:0)
[16:52:09.659] Timer tick, asking Buildbucket for the build status
[16:52:09.731] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:09.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:35:0) after 1m0s
[16:53:09.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:35:0)
[16:53:09.781] Timer tick, asking Buildbucket for the build status
[16:53:09.917] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:09.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:36:0) after 1m0s
[16:54:09.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:36:0)
[16:54:09.959] Timer tick, asking Buildbucket for the build status
[16:54:10.061] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:10.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:37:0) after 1m0s
[16:55:10.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:37:0)
[16:55:10.084] Timer tick, asking Buildbucket for the build status
[16:55:10.293] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:10.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:38:0) after 1m0s
[16:56:10.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:38:0)
[16:56:10.354] Timer tick, asking Buildbucket for the build status
[16:56:10.426] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:10.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:39:0) after 1m0s
[16:57:10.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:39:0)
[16:57:10.464] Timer tick, asking Buildbucket for the build status
[16:57:10.596] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:10.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:40:0) after 1m0s
[16:58:10.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:40:0)
[16:58:10.627] Timer tick, asking Buildbucket for the build status
[16:58:10.803] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:10.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:41:0) after 1m0s
[16:59:10.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:41:0)
[16:59:10.894] Timer tick, asking Buildbucket for the build status
[16:59:11.051] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:11.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:42:0) after 1m0s
[17:00:11.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:42:0)
[17:00:11.059] Timer tick, asking Buildbucket for the build status
[17:00:11.190] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:11.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:43:0) after 1m0s
[17:01:11.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:43:0)
[17:01:11.261] Timer tick, asking Buildbucket for the build status
[17:01:11.369] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:11.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:44:0) after 1m0s
[17:02:11.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:44:0)
[17:02:11.442] Timer tick, asking Buildbucket for the build status
[17:02:11.550] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:11.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:45:0) after 1m0s
[17:03:11.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:45:0)
[17:03:11.879] Timer tick, asking Buildbucket for the build status
[17:03:11.983] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:11.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:46:0) after 1m0s
[17:04:12.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:46:0)
[17:04:12.160] Timer tick, asking Buildbucket for the build status
[17:04:12.536] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:12.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:47:0) after 1m0s
[17:05:12.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:47:0)
[17:05:12.693] Timer tick, asking Buildbucket for the build status
[17:05:12.913] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:12.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:48:0) after 1m0s
[17:06:12.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:48:0)
[17:06:12.890] Timer tick, asking Buildbucket for the build status
[17:06:13.565] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:13.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:49:0) after 1m0s
[17:07:13.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:49:0)
[17:07:13.628] Timer tick, asking Buildbucket for the build status
[17:07:13.723] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:13.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:50:0) after 1m0s
[17:08:13.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:50:0)
[17:08:13.803] Timer tick, asking Buildbucket for the build status
[17:08:13.967] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:13.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:51:0) after 1m0s
[17:09:13.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:51:0)
[17:09:13.995] Timer tick, asking Buildbucket for the build status
[17:09:14.068] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:14.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:52:0) after 1m0s
[17:10:14.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:52:0)
[17:10:14.122] Timer tick, asking Buildbucket for the build status
[17:10:14.403] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:14.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:53:0) after 1m0s
[17:11:14.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:53:0)
[17:11:14.495] Timer tick, asking Buildbucket for the build status
[17:11:14.749] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:14.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:54:0) after 1m0s
[17:12:14.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:54:0)
[17:12:14.881] Timer tick, asking Buildbucket for the build status
[17:12:14.948] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:14.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:55:0) after 1m0s
[17:13:15.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:55:0)
[17:13:15.048] Timer tick, asking Buildbucket for the build status
[17:13:15.324] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:15.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:56:0) after 1m0s
[17:14:15.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:56:0)
[17:14:15.346] Timer tick, asking Buildbucket for the build status
[17:14:15.403] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:15.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:57:0) after 1m0s
[17:15:15.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:57:0)
[17:15:15.445] Timer tick, asking Buildbucket for the build status
[17:15:15.571] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:15.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:58:0) after 1m0s
[17:16:15.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:58:0)
[17:16:15.765] Timer tick, asking Buildbucket for the build status
[17:16:15.860] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:15.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:59:0) after 1m0s
[17:17:16.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:59:0)
[17:17:16.054] Timer tick, asking Buildbucket for the build status
[17:17:16.216] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:16.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:60:0) after 1m0s
[17:18:16.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:60:0)
[17:18:16.269] Timer tick, asking Buildbucket for the build status
[17:18:16.340] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:16.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:61:0) after 1m0s
[17:19:16.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:61:0)
[17:19:16.396] Timer tick, asking Buildbucket for the build status
[17:19:16.507] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:16.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:62:0) after 1m0s
[17:20:16.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:62:0)
[17:20:16.533] Timer tick, asking Buildbucket for the build status
[17:20:16.695] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:63:0) after 1m0s
[17:21:17.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:63:0)
[17:21:17.144] Timer tick, asking Buildbucket for the build status
[17:21:17.528] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:17.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:64:0) after 1m0s
[17:22:17.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:64:0)
[17:22:17.696] Timer tick, asking Buildbucket for the build status
[17:22:17.838] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:17.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:65:0) after 1m0s
[17:23:17.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:65:0)
[17:23:17.928] Timer tick, asking Buildbucket for the build status
[17:23:18.142] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:18.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:66:0) after 1m0s
[17:24:18.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:66:0)
[17:24:18.216] Timer tick, asking Buildbucket for the build status
[17:24:18.289] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:18.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:67:0) after 1m0s
[17:25:18.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:67:0)
[17:25:18.395] Timer tick, asking Buildbucket for the build status
[17:25:18.784] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:18.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:68:0) after 1m0s
[17:26:18.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:68:0)
[17:26:18.911] Timer tick, asking Buildbucket for the build status
[17:26:19.093] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:19.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:69:0) after 1m0s
[17:27:19.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:69:0)
[17:27:19.180] Timer tick, asking Buildbucket for the build status
[17:27:19.330] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:19.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:70:0) after 1m0s
[17:28:19.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:70:0)
[17:28:19.437] Timer tick, asking Buildbucket for the build status
[17:28:19.579] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:19.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:71:0) after 1m0s
[17:29:19.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:71:0)
[17:29:19.691] Timer tick, asking Buildbucket for the build status
[17:29:19.849] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:19.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:72:0) after 1m0s
[17:30:20.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:72:0)
[17:30:20.025] Timer tick, asking Buildbucket for the build status
[17:30:20.417] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:73:0) after 1m0s
[17:31:20.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:73:0)
[17:31:20.671] Timer tick, asking Buildbucket for the build status
[17:31:20.834] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:20.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:74:0) after 1m0s
[17:32:21.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:74:0)
[17:32:21.461] Timer tick, asking Buildbucket for the build status
[17:32:21.992] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:21.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:75:0) after 1m0s
[17:33:22.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:75:0)
[17:33:22.045] Timer tick, asking Buildbucket for the build status
[17:33:22.316] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:22.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:76:0) after 1m0s
[17:34:22.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:76:0)
[17:34:22.447] Timer tick, asking Buildbucket for the build status
[17:34:22.609] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:22.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:77:0) after 1m0s
[17:35:22.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:77:0)
[17:35:22.829] Timer tick, asking Buildbucket for the build status
[17:35:23.090] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:23.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:78:0) after 1m0s
[17:36:23.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:78:0)
[17:36:23.217] Timer tick, asking Buildbucket for the build status
[17:36:23.343] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:23.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:79:0) after 1m0s
[17:37:23.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:79:0)
[17:37:23.433] Timer tick, asking Buildbucket for the build status
[17:37:23.509] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:23.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:80:0) after 1m0s
[17:38:23.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:80:0)
[17:38:23.558] Timer tick, asking Buildbucket for the build status
[17:38:23.635] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:23.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:81:0) after 1m0s
[17:39:23.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:81:0)
[17:39:23.774] Timer tick, asking Buildbucket for the build status
[17:39:23.913] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:23.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:82:0) after 1m0s
[17:40:24.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:82:0)
[17:40:24.029] Timer tick, asking Buildbucket for the build status
[17:40:25.034] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:25.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:83:0) after 1m0s
[17:41:25.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:83:0)
[17:41:25.178] Timer tick, asking Buildbucket for the build status
[17:41:25.286] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:25.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:84:0) after 1m0s
[17:42:25.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:84:0)
[17:42:25.469] Timer tick, asking Buildbucket for the build status
[17:42:25.620] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:25.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:85:0) after 1m0s
[17:43:25.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:85:0)
[17:43:25.894] Timer tick, asking Buildbucket for the build status
[17:43:26.249] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:26.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:86:0) after 1m0s
[17:44:26.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:86:0)
[17:44:26.520] Timer tick, asking Buildbucket for the build status
[17:44:26.660] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:26.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:87:0) after 1m0s
[17:45:26.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:87:0)
[17:45:26.888] Timer tick, asking Buildbucket for the build status
[17:45:27.257] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:27.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:88:0) after 1m0s
[17:46:27.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:88:0)
[17:46:27.433] Timer tick, asking Buildbucket for the build status
[17:46:27.691] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:27.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:89:0) after 1m0s
[17:47:27.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:89:0)
[17:47:27.987] Timer tick, asking Buildbucket for the build status
[17:47:28.209] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:28.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:90:0) after 1m0s
[17:48:28.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:90:0)
[17:48:28.266] Timer tick, asking Buildbucket for the build status
[17:48:28.603] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:28.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:91:0) after 1m0s
[17:49:28.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:91:0)
[17:49:28.735] Timer tick, asking Buildbucket for the build status
[17:49:29.269] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:29.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:92:0) after 1m0s
[17:50:29.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:92:0)
[17:50:29.333] Timer tick, asking Buildbucket for the build status
[17:50:29.803] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:29.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:93:0) after 1m0s
[17:51:29.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:93:0)
[17:51:29.837] Timer tick, asking Buildbucket for the build status
[17:51:30.018] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:30.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:94:0) after 1m0s
[17:52:30.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:94:0)
[17:52:30.105] Timer tick, asking Buildbucket for the build status
[17:52:30.411] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:30.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:95:0) after 1m0s
[17:53:30.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:95:0)
[17:53:30.437] Timer tick, asking Buildbucket for the build status
[17:53:30.504] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:30.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:96:0) after 1m0s
[17:54:30.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:96:0)
[17:54:30.517] Timer tick, asking Buildbucket for the build status
[17:54:30.883] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:30.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:97:0) after 1m0s
[17:55:30.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:97:0)
[17:55:30.914] Timer tick, asking Buildbucket for the build status
[17:55:31.036] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:31.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:98:0) after 1m0s
[17:56:31.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:98:0)
[17:56:31.057] Timer tick, asking Buildbucket for the build status
[17:56:31.278] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:31.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:99:0) after 1m0s
[17:57:31.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:99:0)
[17:57:31.289] Timer tick, asking Buildbucket for the build status
[17:57:31.444] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:31.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:100:0) after 1m0s
[17:58:31.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:100:0)
[17:58:31.516] Timer tick, asking Buildbucket for the build status
[17:58:31.654] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:31.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:101:0) after 1m0s
[17:59:31.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:101:0)
[17:59:31.708] Timer tick, asking Buildbucket for the build status
[17:59:32.008] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:32.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:102:0) after 1m0s
[18:00:32.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:102:0)
[18:00:32.058] Timer tick, asking Buildbucket for the build status
[18:00:32.273] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:32.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:103:0) after 1m0s
[18:01:32.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:103:0)
[18:01:32.256] Timer tick, asking Buildbucket for the build status
[18:01:32.589] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:32.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:104:0) after 1m0s
[18:02:32.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:104:0)
[18:02:32.635] Timer tick, asking Buildbucket for the build status
[18:02:32.710] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:32.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:105:0) after 1m0s
[18:03:32.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:105:0)
[18:03:32.734] Timer tick, asking Buildbucket for the build status
[18:03:33.108] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:33.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:106:0) after 1m0s
[18:04:33.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:106:0)
[18:04:33.172] Timer tick, asking Buildbucket for the build status
[18:04:33.256] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:33.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:107:0) after 1m0s
[18:05:33.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:107:0)
[18:05:33.247] Timer tick, asking Buildbucket for the build status
[18:05:33.776] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:33.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:108:0) after 1m0s
[18:06:33.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:108:0)
[18:06:33.768] Timer tick, asking Buildbucket for the build status
[18:06:34.981] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:34.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:109:0) after 1m0s
[18:07:35.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:109:0)
[18:07:35.001] Timer tick, asking Buildbucket for the build status
[18:07:35.158] Build 8858929384240425744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:35.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024359446155950976:110:0) after 1m0s
[18:07:57.949] Received PubSub notification, asking Buildbucket for the build status
[18:07:58.243] Build 8858929384240425744: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:07:58.243] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609870076936441",
"created_by": "project:chromium",
"created_ts": "1609863597601487",
"failure_reason": "BUILD_FAILURE",
"id": "8858929384240425744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5bdc25c1b9fee531d294e89378767a789fbad570\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}, \"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\": \"5bdc25c1b9fee531d294e89378767a789fbad570\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-8bk2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16641, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"07dea8371353ecea4321e6c96d5da6889b0820c8\", \"got_dawn_revision\": \"200941c797512895039e9f0315fc18fd9d9734ad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"5bdc25c1b9fee531d294e89378767a789fbad570\", \"got_revision_cp\": \"refs/heads/master@{#840165}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"3abec7313f741b57eb8ecfcc3c3e72a7bb229fba\", \"got_v8_revision_cp\": \"refs/heads/8.9.208@{#1}\", \"got_webrtc_revision\": \"b54debc350a18a3f9cce696760786c709beacfaf\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32903}\", \"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\": \"5bdc25c1b9fee531d294e89378767a789fbad570\", \"swarm_hashes_refs/heads/master(at){#840165}_without_patch\": {\"absl_hardening_tests\": \"e0446fc1172e45f90475baf0d7842e3526deab7a\", \"accessibility_unittests\": \"9ae316e29e6d942f3856db0612e557f56d378354\", \"angle_unittests\": \"eea1df39f1b5ff289f15b4d92efb9e5417abce04\", \"app_shell_unittests\": \"f55056b3c163efc5373ed87901a90db9e8f93847\", \"aura_unittests\": \"6f38ea0eec3b6f34b919a142c4221a3dd23f17df\", \"base_unittests\": \"ef6068dc5baf06fcbcea81b736ce1df9c8e0545d\", \"base_util_unittests\": \"0b6a31b1a97e488966309d95f14644448a9b0742\", \"blink_common_unittests\": \"b518c85f744034825ffb23b89685f01342de21a4\", \"blink_fuzzer_unittests\": \"83c910f44b21d2c695811678b19dbeb6878f5172\", \"blink_heap_unittests\": \"55036dceb1173f61ee07efced0367351f629dea9\", \"blink_platform_unittests\": \"3aa6170db26307f9dd879b5b5056418e0fdd1cff\", \"blink_unittests\": \"9250fc8ac9b9e05b6c6d09ad6da9eb81bdc04b43\", \"boringssl_crypto_tests\": \"bd81f157500a30dc099a27f4617447d823af5f79\", \"boringssl_ssl_tests\": \"125067198ad44192ed638e95a07457a890927c2b\", \"browser_switcher_bho_unittests\": \"6ef5d5ed757f0671933db7582f1c343855555c2e\", \"browser_tests\": \"a8593cd27295580a0a50681d3ffa88ddff5ea8ed\", \"capture_unittests\": \"da4314704cacbe6ed4c53779c60d6f489a86f2df\", \"cast_unittests\": \"7f76b88b53abdad8e3f3e68704852c11fd11219d\", \"cc_unittests\": \"eca43a845108500801b982eec72b6de37b57179d\", \"chrome_app_unittests\": \"d88db4b470552e1c4eb71cd61307b649d5b3f6e0\", \"chrome_cleaner_unittests\": \"256c5f073a8ae67b3044a7db955ff4a8a1a76e4d\", \"chrome_elf_unittests\": \"6780f33d4b445c94d7c6778d6439c245bf735bd8\", \"chromedriver_unittests\": \"f9c73532457e50d869785fadb3b22ccb74e550b0\", \"components_browsertests\": \"98f6a1fb025929fe68ba4871ccfece1e0794d4f8\", \"components_unittests\": \"6721701c75e7ea544f9537c5c3433adea48bb124\", \"compositor_unittests\": \"27320dad514b6a3fe969cee69644b605ac4d6211\", \"content_browsertests\": \"583cc6cdfa6aef456e15b8b8b3814a657bf670c0\", \"content_unittests\": \"83bb0b8a688a4da7bcedae80c948ce64b17528c6\", \"courgette_unittests\": \"fefc53c1b8a365dd13436e401845d7f887777c26\", \"crashpad_tests\": \"67c9de064785b6f47f91241c41ebcf4ffc704da9\", \"cronet_tests\": \"1e15f0aa55e6b3b6667a63e4bcce113a2dbde675\", \"cronet_unittests\": \"add6ab5df4c70c96d144dc0eced0b66f1da8f644\", \"crypto_unittests\": \"6c3ec7b814a6082b45257d4fe65d84e3fde42916\", \"delayloads_unittests\": \"441822e43559ec8595fa8002fbcdbcc7fe7f0947\", \"device_unittests\": \"286249e47e453dec56b709809b462beba0e563d0\", \"display_unittests\": \"38beebfbc5af0839a2bc222b29df502a48ba23c1\", \"elevation_service_unittests\": \"a5aa32a95fe336876055bfcfe3f485170e439bf2\", \"events_unittests\": \"fa1408d145ebe9445299a61170f665890fb55ec3\", \"extensions_browsertests\": \"0cc626f64c1a2f748f88b38d294e40f1595dacf3\", \"extensions_unittests\": \"edbdefa3b654b5a44076ef4ab204744d0942e63c\", \"filesystem_service_unittests\": \"cc8119cdcb8a5ea59194ade396d70ad2e3ddff47\", \"gcm_unit_tests\": \"95d7daef0de30ed70f3efe9e17e023806d77aa7e\", \"gcp_unittests\": \"a0295f2ebc678c6a77a795c82b619bf502cab6c4\", \"gfx_unittests\": \"0ba35f7883189c0e83c7afdb50368e6b84a312eb\", \"gin_unittests\": \"09a3489ffdb575e6c782d9d96d2edf94abfc56c1\", \"google_apis_unittests\": \"c6020754ef595565a4f35b757d3f995b6b231b62\", \"gpu_unittests\": \"c7249cf9a095a089811cbfcbb2a8bfac1fff8f0b\", \"gwp_asan_unittests\": \"5e707eb5a3aa7fffd952b77159e576ed105a8ee4\", \"headless_browsertests\": \"d2a2cf323937c8702e51e52fa47e6b4306e257ce\", \"headless_unittests\": \"7d0ab12c75196339366eeb61893308aa9c63a28e\", \"install_static_unittests\": \"c2975dcf262fe8a5ff1468f3a0fa43f2184de087\", \"installer_util_unittests\": \"61ee35580f3e69beb86b1f7c674e4f66213d3227\", \"interactive_ui_tests\": \"c1ba17667c706b22e6f4a60abd41f960d7842bd0\", \"ipc_tests\": \"09f25b0780a81dcf2b579154bbf3b2cb1c349b49\", \"jingle_unittests\": \"5091b2c9a05585851555009e388a841aa38dfc96\", \"latency_unittests\": \"8c39552a572659092c33631ec9a63bc112037dbd\", \"libjingle_xmpp_unittests\": \"8fbf1add053bb1d928e5183f288f79cc1e1cea4f\", \"liburlpattern_unittests\": \"7b77025b393f2a9ecb451920a150296f8282a7a3\", \"media_blink_unittests\": \"61c83fb68e3b904fd9cf55457b0e956195b41166\", \"media_unittests\": \"a4ef25815e76adaf7b89a2e9dd5c797f42c436b8\", \"message_center_unittests\": \"bf43d5417faf78b7452dfa64d34a479d882e6f5d\", \"midi_unittests\": \"271312814348f3b3c78565355637a5297d3cea19\", \"mojo_core_unittests\": \"df076aecaf4fcbbc8a1e2edba7244380a66e152a\", \"mojo_unittests\": \"018a80dc143ab7afe15a678915485e24ef60f075\", \"nacl_loader_unittests\": \"1b13fff3201329451433d0c04c17e07fa156deb6\", \"native_theme_unittests\": \"d5fcb611974b2a5d1d164080842243d1b7c791af\", \"net_unittests\": \"002e08f6136275fde752135ac96502bd5d1361c7\", \"notification_helper_unittests\": \"78cdbf4cbd7ff2aa349b6ebeed515f3938eb9f78\", \"pdf_unittests\": \"9b7de7313a12f3a034fb5f547452b1b0ccf206d8\", \"perfetto_unittests\": \"9fe8bad0ce98f44d5eb0a04794001acb08c6dc9a\", \"ppapi_unittests\": \"2506f2db770e9a283c81fa28055ccd2753e7167b\", \"printing_unittests\": \"d0848ed7b2fe4fe37755eb2209af60ca2afe5223\", \"remoting_unittests\": \"7a0558ec6b92155a74948380fc91a9f9bdab835e\", \"sbox_integration_tests\": \"9c617c80f14d922c9f2c83aceb3fe7bba1292ea5\", \"sbox_unittests\": \"1ffb70f871714efddf1eb151a2fbbde73116f68b\", \"sbox_validation_tests\": \"ce8e8287a7dc2017e91e506acedcb77cb4689ac1\", \"service_manager_unittests\": \"da296e1562b7faa56706fdaf0ceede36a45e2d29\", \"services_unittests\": \"a4f6b16fef528346f33ce22c0ee28e7e5161228c\", \"setup_unittests\": \"0e632cfd00cd2591c8d76cfcfc7a059cdce5bb6b\", \"shell_dialogs_unittests\": \"331c19754ce3c15cab6a71b66a77e5d1f826dd61\", \"skia_unittests\": \"d3cf6979a841ad538d8f24e29f6beba2866788ca\", \"snapshot_unittests\": \"a4d14411e88d3ee51bb2ed2adeec530b28958882\", \"sql_unittests\": \"7d5b5e307d13f5bb3c119adaaff1fcb2698a3217\", \"storage_unittests\": \"f23c5ddf2d72eda9d4bde92289f28f6819e8df78\", \"sync_integration_tests\": \"3e4b7201fec09a385cf45837e9bdc64d6ba31f13\", \"traffic_annotation_auditor_unittests\": \"0708e75fc778ee646f0ba8cded4ca6e685658b7b\", \"ui_base_unittests\": \"a9d58bb8db3359c1828b933de0ce6c90de1bae0c\", \"ui_touch_selection_unittests\": \"74441b0822bcb48584c60de75658521928275400\", \"unit_tests\": \"0d49c302d6e4c02bdc41e15b648e099e8d8c6419\", \"updater_tests\": \"f39ef43ef7c18c692eaa90b4448e3f2408ea8b9e\", \"url_unittests\": \"6eb64ac2ab3086598d5a5949e9630dec902d616c\", \"views_unittests\": \"b50f6f32d925b1d5de0d887a154f51195dfbd64e\", \"viz_unittests\": \"3ce0b2ae03c6cf2c61823c405fe1b017735cd25a\", \"vr_common_unittests\": \"dd5d501b8a72c154dc38b791812c87d8ffd26ad8\", \"vr_pixeltests\": \"801876bc6ece77da7f8486b9e065f4dc1fcaf2ab\", \"weblayer_browsertests\": \"f350eb45d6330aa0122589845de535bbb41f126a\", \"weblayer_unittests\": \"a982775c0450c623b316a522ba1532a94384511d\", \"wm_unittests\": \"dc6d9d030cbe63c043be77796155d582e3d8eed2\", \"wtf_unittests\": \"fb7d6895751d2eaf5a61d9e966c99905b2fc2af8\", \"zlib_unittests\": \"69813fad778d5dbead7182a51ee3930e56e3332f\", \"zucchini_unittests\": \"def459221b8ceb73e098febfd971281c2c111fe3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_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-5-8bk2\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/StartupTracingTest.TestEnableTracing/8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609863870216123",
"status": "COMPLETED",
"status_changed_ts": "1609863870621189",
"tags": [
"build_address:luci.chromium.ci/win-asan/16641",
"builder:win-asan",
"buildset:commit/git/5bdc25c1b9fee531d294e89378767a789fbad570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bdc25c1b9fee531d294e89378767a789fbad570",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024359446155950976",
"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/8858929384240425744/+/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:50ec2a98df203510",
"user_agent:luci-scheduler"
],
"updated_ts": "1609870077554130",
"url": "https://ci.chromium.org/b/8858929384240425744",
"utcnow_ts": "1609870078226683"
}
}
[18:07:58.243] Invocation finished in 1h48m1.852983505s with status FAILED