[03:58:40.846] New invocation is queued and will start shortly
[03:58:41.998] Starting the invocation (attempt 1)
[03:58:42.030] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:58:42.031] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:58:42.031] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020601009572448544",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020601009572448544",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"gitiles_ref:refs/heads/master"
]
}
[03:58:42.701] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613447922109828",
"id": "8855170947584709776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613447922514149",
"tags": [
"build_address:luci.chromium.ci/win-asan/17368",
"builder:win-asan",
"buildset:commit/git/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020601009572448544",
"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/8855170947584709776/+/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": "1613447922514259",
"url": "https://ci.chromium.org/b/8855170947584709776",
"utcnow_ts": "1613447922691608"
}
}
[03:58:42.701] Task URL: https://ci.chromium.org/b/8855170947584709776
[03:58:42.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:2:0) after 1m0s
[03:58:45.187] Received PubSub notification, asking Buildbucket for the build status
[03:58:45.316] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:42.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:2:0)
[03:59:42.756] Timer tick, asking Buildbucket for the build status
[03:59:42.822] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:42.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:4:0) after 1m0s
[04:00:42.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:4:0)
[04:00:42.830] Timer tick, asking Buildbucket for the build status
[04:00:42.954] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:42.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:5:0) after 1m0s
[04:01:42.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:5:0)
[04:01:42.973] Timer tick, asking Buildbucket for the build status
[04:01:43.158] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:43.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:6:0) after 1m0s
[04:02:43.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:6:0)
[04:02:43.178] Timer tick, asking Buildbucket for the build status
[04:02:43.282] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:43.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:7:0) after 1m0s
[04:03:43.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:7:0)
[04:03:43.349] Timer tick, asking Buildbucket for the build status
[04:03:43.470] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:43.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:8:0) after 1m0s
[04:04:43.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:8:0)
[04:04:43.538] Timer tick, asking Buildbucket for the build status
[04:04:43.655] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:9:0) after 1m0s
[04:05:43.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:9:0)
[04:05:43.778] Timer tick, asking Buildbucket for the build status
[04:05:44.084] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:44.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:10:0) after 1m0s
[04:06:44.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:10:0)
[04:06:44.288] Timer tick, asking Buildbucket for the build status
[04:06:44.368] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:44.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:11:0) after 1m0s
[04:07:44.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:11:0)
[04:07:44.388] Timer tick, asking Buildbucket for the build status
[04:07:44.464] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:44.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:12:0) after 1m0s
[04:08:44.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:12:0)
[04:08:44.586] Timer tick, asking Buildbucket for the build status
[04:08:44.895] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:44.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:13:0) after 1m0s
[04:09:44.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:13:0)
[04:09:44.949] Timer tick, asking Buildbucket for the build status
[04:09:45.071] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:45.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:14:0) after 1m0s
[04:10:45.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:14:0)
[04:10:45.214] Timer tick, asking Buildbucket for the build status
[04:10:45.435] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:45.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:15:0) after 1m0s
[04:11:45.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:15:0)
[04:11:45.575] Timer tick, asking Buildbucket for the build status
[04:11:45.792] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:45.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:16:0) after 1m0s
[04:12:45.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:16:0)
[04:12:45.815] Timer tick, asking Buildbucket for the build status
[04:12:45.891] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:45.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:17:0) after 1m0s
[04:13:45.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:17:0)
[04:13:45.912] Timer tick, asking Buildbucket for the build status
[04:13:46.013] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:46.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:18:0) after 1m0s
[04:14:46.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:18:0)
[04:14:46.034] Timer tick, asking Buildbucket for the build status
[04:14:46.129] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:46.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:19:0) after 1m0s
[04:15:46.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:19:0)
[04:15:46.146] Timer tick, asking Buildbucket for the build status
[04:15:46.263] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:46.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:20:0) after 1m0s
[04:16:46.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:20:0)
[04:16:46.306] Timer tick, asking Buildbucket for the build status
[04:16:46.388] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:46.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:21:0) after 1m0s
[04:17:46.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:21:0)
[04:17:46.439] Timer tick, asking Buildbucket for the build status
[04:17:46.531] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:46.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:22:0) after 1m0s
[04:18:46.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:22:0)
[04:18:46.576] Timer tick, asking Buildbucket for the build status
[04:18:46.735] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:46.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:23:0) after 1m0s
[04:19:46.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:23:0)
[04:19:46.809] Timer tick, asking Buildbucket for the build status
[04:19:46.914] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:46.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:24:0) after 1m0s
[04:20:46.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:24:0)
[04:20:46.945] Timer tick, asking Buildbucket for the build status
[04:20:47.063] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:47.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:25:0) after 1m0s
[04:21:47.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:25:0)
[04:21:47.080] Timer tick, asking Buildbucket for the build status
[04:21:47.255] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:47.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:26:0) after 1m0s
[04:22:47.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:26:0)
[04:22:47.241] Timer tick, asking Buildbucket for the build status
[04:22:47.315] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:47.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:27:0) after 1m0s
[04:23:47.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:27:0)
[04:23:47.334] Timer tick, asking Buildbucket for the build status
[04:23:47.454] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:47.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:28:0) after 1m0s
[04:24:47.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:28:0)
[04:24:47.462] Timer tick, asking Buildbucket for the build status
[04:24:47.557] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:47.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:29:0) after 1m0s
[04:25:47.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:29:0)
[04:25:47.566] Timer tick, asking Buildbucket for the build status
[04:25:47.654] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:47.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:30:0) after 1m0s
[04:26:47.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:30:0)
[04:26:47.633] Timer tick, asking Buildbucket for the build status
[04:26:47.898] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:47.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:31:0) after 1m0s
[04:27:47.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:31:0)
[04:27:47.968] Timer tick, asking Buildbucket for the build status
[04:27:48.052] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:48.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:32:0) after 1m0s
[04:28:48.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:32:0)
[04:28:48.073] Timer tick, asking Buildbucket for the build status
[04:28:48.347] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:48.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:33:0) after 1m0s
[04:29:48.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:33:0)
[04:29:48.401] Timer tick, asking Buildbucket for the build status
[04:29:48.483] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:48.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:34:0) after 1m0s
[04:30:48.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:34:0)
[04:30:48.503] Timer tick, asking Buildbucket for the build status
[04:30:48.609] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:48.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:35:0) after 1m0s
[04:31:48.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:35:0)
[04:31:48.665] Timer tick, asking Buildbucket for the build status
[04:31:49.108] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:36:0) after 1m0s
[04:32:49.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:36:0)
[04:32:49.076] Timer tick, asking Buildbucket for the build status
[04:32:49.187] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:37:0) after 1m0s
[04:33:49.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:37:0)
[04:33:49.216] Timer tick, asking Buildbucket for the build status
[04:33:49.313] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:38:0) after 1m0s
[04:34:49.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:38:0)
[04:34:49.353] Timer tick, asking Buildbucket for the build status
[04:34:49.540] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:49.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:39:0) after 1m0s
[04:35:49.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:39:0)
[04:35:49.686] Timer tick, asking Buildbucket for the build status
[04:35:49.794] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:49.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:40:0) after 1m0s
[04:36:49.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:40:0)
[04:36:49.859] Timer tick, asking Buildbucket for the build status
[04:36:49.945] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:49.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:41:0) after 1m0s
[04:37:50.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:41:0)
[04:37:50.142] Timer tick, asking Buildbucket for the build status
[04:37:50.508] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:50.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:42:0) after 1m0s
[04:38:50.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:42:0)
[04:38:50.564] Timer tick, asking Buildbucket for the build status
[04:38:50.661] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:50.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:43:0) after 1m0s
[04:39:50.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:43:0)
[04:39:50.835] Timer tick, asking Buildbucket for the build status
[04:39:50.941] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:50.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:44:0) after 1m0s
[04:40:51.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:44:0)
[04:40:51.032] Timer tick, asking Buildbucket for the build status
[04:40:51.120] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:51.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:45:0) after 1m0s
[04:41:51.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:45:0)
[04:41:51.189] Timer tick, asking Buildbucket for the build status
[04:41:51.366] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:51.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:46:0) after 1m0s
[04:42:51.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:46:0)
[04:42:51.458] Timer tick, asking Buildbucket for the build status
[04:42:51.909] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:51.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:47:0) after 1m0s
[04:43:51.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:47:0)
[04:43:51.914] Timer tick, asking Buildbucket for the build status
[04:43:52.008] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:48:0) after 1m0s
[04:44:52.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:48:0)
[04:44:52.059] Timer tick, asking Buildbucket for the build status
[04:44:52.146] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:52.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:49:0) after 1m0s
[04:45:52.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:49:0)
[04:45:52.162] Timer tick, asking Buildbucket for the build status
[04:45:52.573] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:52.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:50:0) after 1m0s
[04:46:52.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:50:0)
[04:46:52.552] Timer tick, asking Buildbucket for the build status
[04:46:52.689] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:52.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:51:0) after 1m0s
[04:47:52.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:51:0)
[04:47:52.809] Timer tick, asking Buildbucket for the build status
[04:47:52.895] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:52.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:52:0) after 1m0s
[04:48:52.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:52:0)
[04:48:52.922] Timer tick, asking Buildbucket for the build status
[04:48:53.023] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:53.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:53:0) after 1m0s
[04:49:53.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:53:0)
[04:49:53.077] Timer tick, asking Buildbucket for the build status
[04:49:53.162] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:53.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:54:0) after 1m0s
[04:50:53.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:54:0)
[04:50:53.195] Timer tick, asking Buildbucket for the build status
[04:50:53.286] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:53.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:55:0) after 1m0s
[04:51:53.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:55:0)
[04:51:53.328] Timer tick, asking Buildbucket for the build status
[04:51:53.416] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:53.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:56:0) after 1m0s
[04:52:53.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:56:0)
[04:52:53.495] Timer tick, asking Buildbucket for the build status
[04:52:53.593] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:57:0) after 1m0s
[04:53:53.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:57:0)
[04:53:53.647] Timer tick, asking Buildbucket for the build status
[04:53:54.142] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:54.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:58:0) after 1m0s
[04:54:54.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:58:0)
[04:54:54.173] Timer tick, asking Buildbucket for the build status
[04:54:54.389] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:54.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:59:0) after 1m0s
[04:55:54.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:59:0)
[04:55:54.419] Timer tick, asking Buildbucket for the build status
[04:55:54.686] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:54.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:60:0) after 1m0s
[04:56:54.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:60:0)
[04:56:54.746] Timer tick, asking Buildbucket for the build status
[04:56:54.894] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:54.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:61:0) after 1m0s
[04:57:54.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:61:0)
[04:57:54.909] Timer tick, asking Buildbucket for the build status
[04:57:54.984] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:54.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:62:0) after 1m0s
[04:58:55.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:62:0)
[04:58:55.008] Timer tick, asking Buildbucket for the build status
[04:58:55.115] Build 8855170947584709776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:55.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020601009572448544:63:0) after 1m0s
[04:59:21.205] Received PubSub notification, asking Buildbucket for the build status
[04:59:21.295] Build 8855170947584709776: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:59:21.295] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613451559197172",
"created_by": "project:chromium",
"created_ts": "1613447922109828",
"failure_reason": "BUILD_FAILURE",
"id": "8855170947584709776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- GcpSetupTest.DoInstall\\n\\n- GcpSetupTest.DoInstallWritesUninstallStrings\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/6\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/5\\n\\n- ...15 more failure(s) (19 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\": \"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17368, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\", \"got_revision_cp\": \"refs/heads/master@{#854139}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ebacb645b20531cd78dc088730fb72269822eeb6\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#2}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"aec2bcf60dd6f3ccbd560158c2a5a916ae715caa\", \"swarm_hashes_refs/heads/master(at){#854139}_without_patch\": {\"absl_hardening_tests\": \"e3b1188b5fad238d6f229bec22f1743b1238d9ff\", \"accessibility_unittests\": \"6043d09e5b1c71e4b381bb0d66da542bf0ca0a07\", \"angle_unittests\": \"825cf5d49f56a119d15884eff60eb30d1f3f5fb8\", \"app_shell_unittests\": \"9e6f6b8ba4bfa49c3eebe2e0fc8f8870074f22b1\", \"aura_unittests\": \"a06bc168ac33a9e3f38d8f807d902b4b02f9e6c2\", \"base_unittests\": \"785254e20cd6bd35c33a80e515ef2a1c45203c17\", \"base_util_unittests\": \"2cba5f008f6330d2c5c6059f173c454c47e888ea\", \"blink_common_unittests\": \"f4842e2c4b08855489ff56994ad815fa3be2a677\", \"blink_fuzzer_unittests\": \"451a9e1106cf20fd32ea918034f0982b19bdc06c\", \"blink_heap_unittests\": \"a758c802da8de03b6b22083ed86af97307087cc8\", \"blink_platform_unittests\": \"0f82ee4ce6b1a3e4a29537d1e0da284af1398dae\", \"blink_unittests\": \"04394aa971b206dd2ac7bf46624b0775436544ea\", \"boringssl_crypto_tests\": \"e453d8fb80cee363177f3af7c343f6c3a86b3d13\", \"boringssl_ssl_tests\": \"afa4a155e532b35133044e699839888f5d9bf45d\", \"browser_switcher_bho_unittests\": \"4d769f0b7cd5a4f8a51cf51be4378f0de55d1d20\", \"browser_tests\": \"ab8b9856b1ac764f909d3f3781ec4545bd8b08f4\", \"capture_unittests\": \"2a956705ef0ea94bdec9743068e6adfc7e3191c3\", \"cast_unittests\": \"fc59736b647fd7aa2363f3d40d81e4d587e151cb\", \"cc_unittests\": \"b3a94d4daa043d6e73c0b6b09a756b725d7fca47\", \"chrome_app_unittests\": \"8b6bd0885abfb44223cb4f389ceffb2c088c6aac\", \"chrome_cleaner_unittests\": \"f4eb7b8d399758abfbdb635be533a7a535629417\", \"chrome_elf_unittests\": \"4df5b5e9ea5c3dae6c70cee7a39251bd2838fddc\", \"chromedriver_unittests\": \"af6a81c21a19d1575111b13050e11fc952cf3dc6\", \"components_browsertests\": \"86c7dccf4a44b4702208445d80348b58edecd071\", \"components_unittests\": \"255df75d099436784f50641320f6dddef278517d\", \"compositor_unittests\": \"516dabbf920afc6dff729863645cd0ecf72b7aa1\", \"content_browsertests\": \"c7ca2500623b24e039dfade717425adfe0776fbf\", \"content_unittests\": \"e3886ca96ba82737bdf2e7a5afdca13b49d5db99\", \"courgette_unittests\": \"13418605631c010b5ace8d92ca36d5dc9f1fa485\", \"crashpad_tests\": \"c13ba8aa0d80dbd6fce4a1754159b76557e9f399\", \"cronet_tests\": \"fc0ac87e795fb3858b22290c9c5775f3a85b0e55\", \"cronet_unittests\": \"e87ede121216ad430a70d9906225494cd92a5f39\", \"crypto_unittests\": \"13812472fc3d571411430677e0f45e97d1bc6d50\", \"delayloads_unittests\": \"295cae56ca7c6771124a0a0a2a9e2731232cf76f\", \"device_unittests\": \"af55cf457da90bf19738efd9020219b05bdd85d1\", \"display_unittests\": \"213de280977e43505ce7aa82e9ff7d169363657e\", \"elevation_service_unittests\": \"82929bab4bb0798ca5e673a5d8675e1222edfb3f\", \"events_unittests\": \"2c7cdb2f109096570ae928481d85ab18a0313c21\", \"extensions_browsertests\": \"bee6b50716d14ee563b67193a2637a81d9205dd0\", \"extensions_unittests\": \"a109718982783b04f8f485d3848c106e058d301e\", \"filesystem_service_unittests\": \"0b09869a337516f997ea8b25807ca91ec24a1ac4\", \"gcm_unit_tests\": \"18e937e6dc270ea8494156260ddde15068bbf0cd\", \"gcp_unittests\": \"9531671e909850f598aad293686b16e32d07fe32\", \"gfx_unittests\": \"54b76b4f20c5257f12a9b83935899e7dac7bd2ac\", \"gin_unittests\": \"8962850f51be3f38dd69ca9811c8d90f3f1bdf9b\", \"google_apis_unittests\": \"26ef3a5baff8cf2324910529623104243ecb1a72\", \"gpu_unittests\": \"52416e9981e21252eab2a59c17141bdc5ee99b36\", \"gwp_asan_unittests\": \"dbbd3ab0fa39fe49942932361a2ca26e23b0f886\", \"headless_browsertests\": \"5087f216a406d7a2d7a64fb2a78d4d81f01299b4\", \"headless_unittests\": \"6abf4877025e0f403325f5cfd190b4449310e52d\", \"install_static_unittests\": \"ca6f27a19be4579ee38f46cb51a092f58a82ced0\", \"installer_util_unittests\": \"e40945dda5508c77967f534cad0761fe0a23ba9e\", \"interactive_ui_tests\": \"82c7e19b8f0c9c38f872df8e3abb9529ffefcb93\", \"ipc_tests\": \"3a969fb9c9463f171c50676932a0b41c4e095673\", \"jingle_unittests\": \"55e3c31c5e97416419ea192d5cff78f57ab97f27\", \"latency_unittests\": \"69d274308ed126b4cca940ff557a2c0236a95c32\", \"libjingle_xmpp_unittests\": \"bdb664e4f2dc2c8af3840bacd5c27ceb59e49980\", \"liburlpattern_unittests\": \"e844e823a53d1a78b907d636fdb20126a9af5cfa\", \"media_blink_unittests\": \"748ad501ab70bdf9775833e2eb2ab68b5f4f4be7\", \"media_unittests\": \"cc69faf2b0af2152e04421bd967e341e649d0432\", \"message_center_unittests\": \"214ca30f29c4a3599af6f2c5e7c278714a7c5163\", \"midi_unittests\": \"483334dadc3fefa6a77250a5852e162ca8138214\", \"mojo_core_unittests\": \"8c818f763b75103bddf464ddd44804b3a7d6309a\", \"mojo_unittests\": \"99d02ab7efbd8733588d4644e3656cbe13d85278\", \"nacl_loader_unittests\": \"a479aabcd7bc477f29b1aafcca66d309d1c754bb\", \"native_theme_unittests\": \"c087feca606704d23f04785aef4c68b1cf166712\", \"net_unittests\": \"11d1fde15e58cd36b5c063f53e8234898c02f6ae\", \"notification_helper_unittests\": \"ce0e6ce1693f8b2a5c7ebfcd4331aea1032c86d0\", \"pdf_unittests\": \"eb48d8194fbdc70920268b3cfae3f0f22b520454\", \"perfetto_unittests\": \"39895e2b2700a8648315c2c2826544055acfbbd1\", \"ppapi_unittests\": \"69c5975575b36f22d543fe84cefa170f4bef1d24\", \"printing_unittests\": \"876f7d7337e5a2ce12769b668ff0438781bfde78\", \"remoting_unittests\": \"29040c354e62c2e5b6c8ae78a36dba4c4fec0cce\", \"sbox_integration_tests\": \"860de998907bec8da442ece4ad0b0283c51c0dd8\", \"sbox_unittests\": \"630e831656cd65b1214c301b476fdb005e79deb0\", \"sbox_validation_tests\": \"7f7cdb2c92977ba383951e8b9c44295eb5a9260f\", \"service_manager_unittests\": \"672d41d4bd6c75491b5ac482386eb00d58caf736\", \"services_unittests\": \"1135779f9f9311d3e58ac20247879deae1c2c990\", \"setup_unittests\": \"4d832c0a30e8d74edf71e8234084480a62b6f0ef\", \"shell_dialogs_unittests\": \"672e68bc0991028e232e273f0d5d12025b586d2f\", \"skia_unittests\": \"aa39681752e724255a56d8af013c046725ee4c84\", \"snapshot_unittests\": \"1d09c6d7a30e0786001a087ecd5153cbf66ab6e5\", \"sql_unittests\": \"e0cfcc8283db937a9caaed5706adde1a98beca2c\", \"storage_unittests\": \"6aac3357a81778214457aba4a5a59e9d1776f008\", \"sync_integration_tests\": \"a396dd6f226b247ec49fc7d851e9ff9a3b5a47e0\", \"traffic_annotation_auditor_unittests\": \"84ad7c2b2b7fcb937c2dbb97af067788c6f55a3a\", \"ui_base_unittests\": \"3a469baa3d238d01090f0a51781413db763e7abb\", \"ui_touch_selection_unittests\": \"0cedca17fe30bf267af180f3180319ec09ca49a3\", \"unit_tests\": \"7ac40cf4778d6b2d4c17f5aab41a78bd89bc0ca1\", \"updater_tests\": \"a1830f68c9733623767a7decb0bede699ba96121\", \"url_unittests\": \"c38d24df0fa4634830ed808cc203dfd5781584b3\", \"views_unittests\": \"aa6f3e61c97dcccee6240d31355fe83274794350\", \"viz_unittests\": \"742bce5135748ff6a1706aced60be8215d112d37\", \"vr_common_unittests\": \"49206fdf565cf429c01ff4453162898c7acd99c5\", \"vr_pixeltests\": \"f6fa9f32f0ddf9b163b9f30940a05383a28107c8\", \"weblayer_browsertests\": \"572f4859003ead8702a69d92941b0b2f2fbe27e1\", \"weblayer_unittests\": \"b59bbe6203d9148fffa82fd3fef5626cabe4207d\", \"wm_unittests\": \"75a246457e01bed54ad81bbbf04e05abac2acd7d\", \"wtf_unittests\": \"56d40ab26cb8501e89f56bdd50dd25cc7cbc2f85\", \"zlib_unittests\": \"2ec4857729edb34bc28a3e39ded86866b4a2c7b7\", \"zucchini_unittests\": \"d5fb8bac5ae5af03a2b58b0579838ff6ce2b83cf\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**gcp_unittests** failed because of:\\n\\n- GcpSetupTest.DoInstall\\n\\n- GcpSetupTest.DoInstallWritesUninstallStrings\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/6\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/5\\n\\n- ...15 more failure(s) (19 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613447923595562",
"status": "COMPLETED",
"status_changed_ts": "1613447924931970",
"tags": [
"build_address:luci.chromium.ci/win-asan/17368",
"builder:win-asan",
"buildset:commit/git/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aec2bcf60dd6f3ccbd560158c2a5a916ae715caa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020601009572448544",
"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/8855170947584709776/+/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:51c1cf0d332e0910",
"user_agent:luci-scheduler"
],
"updated_ts": "1613451560730370",
"url": "https://ci.chromium.org/b/8855170947584709776",
"utcnow_ts": "1613451561283794"
}
}
[04:59:21.295] Invocation finished in 1h0m40.464625982s with status FAILED