[10:12:30.278] New invocation is queued and will start shortly
[10:12:31.361] Starting the invocation (attempt 1)
[10:12:31.397] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:12:31.397] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:12:31.397] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018765551279472096",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a533d5f821569de62406636d12f3d4542d92e7fe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018765551279472096",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a533d5f821569de62406636d12f3d4542d92e7fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a533d5f821569de62406636d12f3d4542d92e7fe",
"gitiles_ref:refs/heads/master"
]
}
[10:12:31.978] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615198351479019",
"id": "8853335489357497312",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a533d5f821569de62406636d12f3d4542d92e7fe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615198351862079",
"tags": [
"build_address:luci.chromium.ci/win-asan/17700",
"builder:win-asan",
"buildset:commit/git/a533d5f821569de62406636d12f3d4542d92e7fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a533d5f821569de62406636d12f3d4542d92e7fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018765551279472096",
"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/8853335489357497312/+/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": "1615198351862188",
"url": "https://ci.chromium.org/b/8853335489357497312",
"utcnow_ts": "1615198351971358"
}
}
[10:12:31.978] Task URL: https://ci.chromium.org/b/8853335489357497312
[10:12:31.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:2:0) after 1m0s
[10:12:34.886] Received PubSub notification, asking Buildbucket for the build status
[10:12:35.082] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:32.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:2:0)
[10:13:32.136] Timer tick, asking Buildbucket for the build status
[10:13:32.205] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:32.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:4:0) after 1m0s
[10:14:32.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:4:0)
[10:14:32.325] Timer tick, asking Buildbucket for the build status
[10:14:32.463] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:32.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:5:0) after 1m0s
[10:15:32.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:5:0)
[10:15:32.457] Timer tick, asking Buildbucket for the build status
[10:15:32.691] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:6:0) after 1m0s
[10:16:32.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:6:0)
[10:16:32.685] Timer tick, asking Buildbucket for the build status
[10:16:32.844] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:32.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:7:0) after 1m0s
[10:17:32.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:7:0)
[10:17:32.859] Timer tick, asking Buildbucket for the build status
[10:17:32.949] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:32.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:8:0) after 1m0s
[10:18:32.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:8:0)
[10:18:32.947] Timer tick, asking Buildbucket for the build status
[10:18:33.007] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:9:0) after 1m0s
[10:19:33.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:9:0)
[10:19:33.031] Timer tick, asking Buildbucket for the build status
[10:19:33.087] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:33.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:10:0) after 1m0s
[10:20:33.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:10:0)
[10:20:33.063] Timer tick, asking Buildbucket for the build status
[10:20:33.232] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:11:0) after 1m0s
[10:21:33.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:11:0)
[10:21:33.246] Timer tick, asking Buildbucket for the build status
[10:21:33.330] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:33.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:12:0) after 1m0s
[10:22:33.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:12:0)
[10:22:33.345] Timer tick, asking Buildbucket for the build status
[10:22:33.515] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:33.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:13:0) after 1m0s
[10:23:33.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:13:0)
[10:23:33.502] Timer tick, asking Buildbucket for the build status
[10:23:33.580] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:33.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:14:0) after 1m0s
[10:24:33.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:14:0)
[10:24:33.609] Timer tick, asking Buildbucket for the build status
[10:24:33.681] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:33.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:15:0) after 1m0s
[10:25:33.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:15:0)
[10:25:33.756] Timer tick, asking Buildbucket for the build status
[10:25:33.825] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:33.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:16:0) after 1m0s
[10:26:33.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:16:0)
[10:26:33.844] Timer tick, asking Buildbucket for the build status
[10:26:34.002] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:34.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:17:0) after 1m0s
[10:27:34.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:17:0)
[10:27:34.070] Timer tick, asking Buildbucket for the build status
[10:27:34.166] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:34.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:18:0) after 1m0s
[10:28:34.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:18:0)
[10:28:34.188] Timer tick, asking Buildbucket for the build status
[10:28:34.252] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:34.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:19:0) after 1m0s
[10:29:34.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:19:0)
[10:29:34.302] Timer tick, asking Buildbucket for the build status
[10:29:34.403] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:34.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:20:0) after 1m0s
[10:30:34.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:20:0)
[10:30:34.542] Timer tick, asking Buildbucket for the build status
[10:30:34.913] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:34.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:21:0) after 1m0s
[10:31:34.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:21:0)
[10:31:34.973] Timer tick, asking Buildbucket for the build status
[10:31:35.713] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:22:0) after 1m0s
[10:32:35.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:22:0)
[10:32:35.887] Timer tick, asking Buildbucket for the build status
[10:32:36.031] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:36.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:23:0) after 1m0s
[10:33:36.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:23:0)
[10:33:36.145] Timer tick, asking Buildbucket for the build status
[10:33:36.228] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:36.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:24:0) after 1m0s
[10:34:36.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:24:0)
[10:34:36.280] Timer tick, asking Buildbucket for the build status
[10:34:36.526] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:36.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:25:0) after 1m0s
[10:35:36.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:25:0)
[10:35:36.610] Timer tick, asking Buildbucket for the build status
[10:35:36.712] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:36.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:26:0) after 1m0s
[10:36:36.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:26:0)
[10:36:36.752] Timer tick, asking Buildbucket for the build status
[10:36:36.814] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:36.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:27:0) after 1m0s
[10:37:36.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:27:0)
[10:37:36.960] Timer tick, asking Buildbucket for the build status
[10:37:37.062] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:37.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:28:0) after 1m0s
[10:38:37.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:28:0)
[10:38:37.120] Timer tick, asking Buildbucket for the build status
[10:38:37.292] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:37.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:29:0) after 1m0s
[10:39:37.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:29:0)
[10:39:37.319] Timer tick, asking Buildbucket for the build status
[10:39:37.715] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:37.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:30:0) after 1m0s
[10:40:37.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:30:0)
[10:40:37.852] Timer tick, asking Buildbucket for the build status
[10:40:37.936] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:37.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:31:0) after 1m0s
[10:41:38.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:31:0)
[10:41:38.018] Timer tick, asking Buildbucket for the build status
[10:41:38.651] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:38.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:32:0) after 1m0s
[10:42:39.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:32:0)
[10:42:39.109] Timer tick, asking Buildbucket for the build status
[10:42:39.183] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:39.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:33:0) after 1m0s
[10:43:39.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:33:0)
[10:43:39.284] Timer tick, asking Buildbucket for the build status
[10:43:39.714] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:39.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:34:0) after 1m0s
[10:44:39.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:34:0)
[10:44:39.852] Timer tick, asking Buildbucket for the build status
[10:44:39.944] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:39.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:35:0) after 1m0s
[10:45:40.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:35:0)
[10:45:40.466] Timer tick, asking Buildbucket for the build status
[10:45:40.583] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:40.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:36:0) after 1m0s
[10:46:40.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:36:0)
[10:46:40.676] Timer tick, asking Buildbucket for the build status
[10:46:40.743] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:37:0) after 1m0s
[10:47:40.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:37:0)
[10:47:40.775] Timer tick, asking Buildbucket for the build status
[10:47:40.883] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:40.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:38:0) after 1m0s
[10:48:40.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:38:0)
[10:48:40.959] Timer tick, asking Buildbucket for the build status
[10:48:41.036] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:41.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:39:0) after 1m0s
[10:49:41.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:39:0)
[10:49:41.033] Timer tick, asking Buildbucket for the build status
[10:49:41.243] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:41.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:40:0) after 1m0s
[10:50:41.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:40:0)
[10:50:41.322] Timer tick, asking Buildbucket for the build status
[10:50:41.412] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:41.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:41:0) after 1m0s
[10:51:41.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:41:0)
[10:51:41.420] Timer tick, asking Buildbucket for the build status
[10:51:42.426] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:42.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:42:0) after 1m0s
[10:52:42.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:42:0)
[10:52:42.443] Timer tick, asking Buildbucket for the build status
[10:52:42.583] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:42.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:43:0) after 1m0s
[10:53:42.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:43:0)
[10:53:42.600] Timer tick, asking Buildbucket for the build status
[10:53:42.698] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:42.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:44:0) after 1m0s
[10:54:42.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:44:0)
[10:54:42.715] Timer tick, asking Buildbucket for the build status
[10:54:43.115] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:43.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:45:0) after 1m0s
[10:55:43.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:45:0)
[10:55:43.172] Timer tick, asking Buildbucket for the build status
[10:55:43.281] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:43.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:46:0) after 1m0s
[10:56:43.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:46:0)
[10:56:43.295] Timer tick, asking Buildbucket for the build status
[10:56:43.398] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:43.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:47:0) after 1m0s
[10:57:43.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:47:0)
[10:57:43.438] Timer tick, asking Buildbucket for the build status
[10:57:43.600] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:43.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:48:0) after 1m0s
[10:58:43.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:48:0)
[10:58:43.714] Timer tick, asking Buildbucket for the build status
[10:58:43.796] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:43.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:49:0) after 1m0s
[10:59:43.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:49:0)
[10:59:43.793] Timer tick, asking Buildbucket for the build status
[10:59:44.119] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:44.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:50:0) after 1m0s
[11:00:44.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:50:0)
[11:00:44.190] Timer tick, asking Buildbucket for the build status
[11:00:44.292] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:44.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:51:0) after 1m0s
[11:01:44.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:51:0)
[11:01:44.351] Timer tick, asking Buildbucket for the build status
[11:01:44.625] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:44.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:52:0) after 1m0s
[11:02:44.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:52:0)
[11:02:44.697] Timer tick, asking Buildbucket for the build status
[11:02:44.966] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:44.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:53:0) after 1m0s
[11:03:44.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:53:0)
[11:03:44.980] Timer tick, asking Buildbucket for the build status
[11:03:45.066] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:45.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:54:0) after 1m0s
[11:04:45.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:54:0)
[11:04:45.105] Timer tick, asking Buildbucket for the build status
[11:04:45.195] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:45.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:55:0) after 1m0s
[11:05:45.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:55:0)
[11:05:45.216] Timer tick, asking Buildbucket for the build status
[11:05:45.327] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:56:0) after 1m0s
[11:06:45.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:56:0)
[11:06:45.383] Timer tick, asking Buildbucket for the build status
[11:06:45.458] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:45.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:57:0) after 1m0s
[11:07:45.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:57:0)
[11:07:45.527] Timer tick, asking Buildbucket for the build status
[11:07:45.615] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:45.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:58:0) after 1m0s
[11:08:45.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:58:0)
[11:08:45.609] Timer tick, asking Buildbucket for the build status
[11:08:45.702] Build 8853335489357497312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:45.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018765551279472096:59:0) after 1m0s
[11:08:59.951] Received PubSub notification, asking Buildbucket for the build status
[11:09:00.155] Build 8853335489357497312: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:09:00.155] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615201739602792",
"created_by": "project:chromium",
"created_ts": "1615198351479019",
"failure_reason": "BUILD_FAILURE",
"id": "8853335489357497312",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a533d5f821569de62406636d12f3d4542d92e7fe\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- Capture_Monitor/ECKEncryptedMediaOutputProtectionTest.BeforeMediaKeys/0\\n\\n- FormAutofillTest.FillFormWithPlaceholderValues\\n\\n- PasswordAutofillAgentTest.SendPasswordFormsTest_CannotCreatePasswordForm\\n\\n- ChromeContentRendererClientSearchBoxTest.RewriteThumbnailURL\\n\\n- ...6 more failure(s) (10 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\": \"a533d5f821569de62406636d12f3d4542d92e7fe\", \"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-8-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17700, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-1\", \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a533d5f821569de62406636d12f3d4542d92e7fe\", \"got_revision_cp\": \"refs/heads/master@{#860662}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"f8b883fbba96b155409eb00da93826216bea9fc0\", \"got_v8_revision_cp\": \"refs/heads/9.1.60@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a533d5f821569de62406636d12f3d4542d92e7fe\", \"swarm_hashes_refs/heads/master(at){#860662}_without_patch\": {\"absl_hardening_tests\": \"d4839ba9ba86aa9ffadb155ff50ff33ad1c9c273\", \"accessibility_unittests\": \"f4ee984c16a69a232c240b20cc6182aad2881911\", \"angle_unittests\": \"75f9bc528c302ac620423c70f3150893ba7ed744\", \"app_shell_unittests\": \"da365279813f44d8a00ebf45da7772fc6488ea23\", \"aura_unittests\": \"828152459350d80f57c74d2772f090b704fefc06\", \"base_unittests\": \"7d80f301d07762f0e925b08fd1c431fd476d1c77\", \"base_util_unittests\": \"6c7aff3a502fbe72fe994697271be85155c44887\", \"blink_common_unittests\": \"5f7434a4618569f26971da87d6fb83d455a39c2a\", \"blink_fuzzer_unittests\": \"809eb4a1be17960d5989f2a5f1cf1b10a72527ef\", \"blink_heap_unittests\": \"900f25a9e102d784721e64d9dc66bd3b8e83f7fe\", \"blink_platform_unittests\": \"dcd5c66a0b37bee5cbe5efead349eded8c792505\", \"blink_unittests\": \"ff04f56b0a4370a2c11d91b056863d8355744336\", \"boringssl_crypto_tests\": \"2a54773cc316a3fed584710b7273f5223b1da399\", \"boringssl_ssl_tests\": \"90202be014d41cfbac1d84ca0b7e1b6b14a02286\", \"browser_switcher_bho_unittests\": \"529d8dd3a3b0c3b32ee3c0de2b753ccd52096b5c\", \"browser_tests\": \"5ecc76cb820464a1d6bed6f65bc34f9553c71268\", \"capture_unittests\": \"1f1da72cc610a605d4b78e2426174c60875d0f9f\", \"cast_unittests\": \"4bdd245638db57d0648ed5e678ea419b0c2f0b01\", \"cc_unittests\": \"b183ce2624056d8fa63a083417056aadf830d297\", \"chrome_app_unittests\": \"1f97d571900715748f2accce0f3c427827e140ba\", \"chrome_cleaner_unittests\": \"9c51146347a18af3988a3f1b631e5dff6727a70e\", \"chrome_elf_unittests\": \"5a69080e1055b51cd0ef08eedaa93ace926f50ae\", \"chromedriver_unittests\": \"2c21ab607e6759684c207fb58b2b2af515b0b66b\", \"color_unittests\": \"93adf28313ebccea6d7c1bdc760ade24925841b5\", \"components_browsertests\": \"5e262feefb205ce880aaa1aea7ab53eeaa51945e\", \"components_unittests\": \"ccb1ea94794861bd2460603cb749d00f3c96ad70\", \"compositor_unittests\": \"b964789c98b9eca37897786db0cc3b0f29e6f1d9\", \"content_browsertests\": \"e1b4c529e6a036f42d470bcc82e185023b806069\", \"content_unittests\": \"7359a3d9b263afa44c2fc709afd8f1b258b949fc\", \"courgette_unittests\": \"453de8095e9ebf6ab3ff0c4ae5c27ff16ce89cf2\", \"crashpad_tests\": \"9777fe37a2c0f60e8fe2d80b331cd74544c4c20b\", \"cronet_tests\": \"0f1bab4a7e27e32c69e0797ec4741a67242b9447\", \"cronet_unittests\": \"3781c68f70f6944a330965b606af3353f79d422a\", \"crypto_unittests\": \"d41e10803caa055afe2d8c898d999ad308dcdec7\", \"delayloads_unittests\": \"84ba9b022e0fa18064eacaa0d2ae6260af90d414\", \"device_unittests\": \"c4d233adc166ab1e4499e1cb4c398ff458d73966\", \"display_unittests\": \"e42fd6f153c2a627fee9f5f8f0c2befa510e974e\", \"elevation_service_unittests\": \"f8327799188ca6e0ea662ff21471da13b5dd66a5\", \"events_unittests\": \"d37d13eddef04cd8e177f25866d5327b79895819\", \"extensions_browsertests\": \"72deb70a7bd292a14c9c2ec390e2c4069ba3fcb2\", \"extensions_unittests\": \"fa1df40ee8e36155462e96c5e95455ec0122888d\", \"filesystem_service_unittests\": \"d3222b0d2b8ebf89e1c9fde6dd1aaf6597604c32\", \"gcm_unit_tests\": \"a9e6bfbeb33266d6166ef26dc41db379f8b3a81b\", \"gcp_unittests\": \"c37d31a9a3f006dc5e0469bd3961523da929e5f7\", \"gfx_unittests\": \"1dd7564f4a6d326a84a0451fcbd3cc7e2a1c7440\", \"gin_unittests\": \"205708feeac91f3b9453147ffb2df669e3e5973d\", \"google_apis_unittests\": \"88ff0bd5fa0f678ac2209d2c78fd9ab6cb572c92\", \"gpu_unittests\": \"99f015c6cc9c2fae6753b560051b4dae2d5bb2e6\", \"gwp_asan_unittests\": \"91f6d5380ef34bca005ed20808e0c31e3ee771af\", \"headless_browsertests\": \"03d47d9d6a93ad93c5905dce9b81c4700c2d31c6\", \"headless_unittests\": \"416352d95abbdb5c1a7e45a3473c50b2e41d8da3\", \"install_static_unittests\": \"1a0c5695fc9b32889281a95cfc641ab1bf07d641\", \"installer_util_unittests\": \"2db48523f559f687c3ad9ab044d718d5f0aad980\", \"interactive_ui_tests\": \"86e3827386c5d1ddadb89badd3e0d789fd5c26d4\", \"ipc_tests\": \"067a52d165efca269b360fbe1bd8b1e740ea8811\", \"jingle_unittests\": \"b4267e7d57d3155adab40accfedbcbd2d131d73b\", \"latency_unittests\": \"0cd31ed5b708694f9dbeb55a8566ba6cb049a44e\", \"libjingle_xmpp_unittests\": \"c456698b7c143598953d02e550b1bbb47f3452b3\", \"liburlpattern_unittests\": \"e0603e4eabf5056a04ccb5fc4cfe148534046ecb\", \"media_blink_unittests\": \"a736cfb011b3199b32586e9f957eda717d48c0aa\", \"media_unittests\": \"fd970733ceb375ee8cd751cd2abb069b14764c52\", \"message_center_unittests\": \"f005093ff703dcc35db26c913ccfd6adf60bbc86\", \"midi_unittests\": \"703fbe32c825fbd92a5921c434be721151dfe4b9\", \"mojo_core_unittests\": \"0195c8fe41db7da16fe5f27046763f6a17dcc7d3\", \"mojo_unittests\": \"3c53f3e9967407f29bb55911d9ca765b1891e275\", \"nacl_loader_unittests\": \"11a04df00069ff087a28a80b0bbe9ab71401867e\", \"native_theme_unittests\": \"4b22627e62f87b31237525a8d1b57b67bb302366\", \"net_unittests\": \"6a03498d68f9946d26acd039fdaa4cb8e90a02fd\", \"notification_helper_unittests\": \"b700ead97b508b97d67dae70609721f5933bd61d\", \"pdf_unittests\": \"7907be9ebf47ac32d28e8d6f43cf253b250b33e1\", \"perfetto_unittests\": \"87cadc504bb74f94c5d3fb2b874e8ea3b0eaf945\", \"ppapi_unittests\": \"378fe488fdcbe34a89eba6c54d87e9abf95ef7e0\", \"printing_unittests\": \"038e27ed159d01fec3e0522b75d38795d32d2f3a\", \"remoting_unittests\": \"2a958475f7aaa8a0d4705d03653c8649e7cae882\", \"sbox_integration_tests\": \"0c22f9887979fc514e1b4e953b33701666b241de\", \"sbox_unittests\": \"020e1ba2a12d67758e03088ad717d51a469d5c86\", \"sbox_validation_tests\": \"768e021832eaa38bba256529e61321f040e11ac7\", \"service_manager_unittests\": \"ed8caca6c3b519a2c33b7006f84c0b14664d97d6\", \"services_unittests\": \"7dac28127b7efbbb285cdc375705876341a344c9\", \"setup_unittests\": \"f100619cb77962f2ce13e21efbf7e583297515d8\", \"shell_dialogs_unittests\": \"7fe89c4482f49b76ef058f5d0e5498205652f89e\", \"skia_unittests\": \"26aa25f77f0dee811a4fc872c28cd72fa8466ea7\", \"snapshot_unittests\": \"03367212ccd445fe60b9083d4c9e23530b95d68c\", \"sql_unittests\": \"7ea02dd40ad27d26abe661d9fbe41056d238e2c4\", \"storage_unittests\": \"0509eea7dc894b8877d83ea74e99fe587478be42\", \"sync_integration_tests\": \"c4cdb989f4de11f94db85280e26680bdf0f98474\", \"traffic_annotation_auditor_unittests\": \"c1b3f37593321a6d777be72a9b71f0554fd2df29\", \"ui_base_unittests\": \"679d80985f63ceb3ca4370ee61159d5126957a33\", \"ui_touch_selection_unittests\": \"78afe950c5a06704f2e6075c76a93f67940b8b33\", \"unit_tests\": \"e14c3cb5ba991efb29cd0e906ffa4e2a3319e396\", \"updater_tests\": \"823a822adc29d08c311001db82d130f2a006d4f6\", \"url_unittests\": \"ae5c8f19552eca2df39f13252b6b62ebcb138fc7\", \"views_unittests\": \"b5e767065b55f98174ae49d9baecd188bf0be7cd\", \"viz_unittests\": \"2c74eed9be17d1dc2d80b815978c2fcb5554aaf0\", \"vr_common_unittests\": \"da5cf456243919096cf5983c982967996c3764c8\", \"vr_pixeltests\": \"306f914502dcd4cfc14e5cb48a09536b868227ff\", \"weblayer_browsertests\": \"fdd6a0fd92e7be6729f3730c4a7046a78b55cf44\", \"weblayer_unittests\": \"0ca7253f892bc64768dfc342ae37990d2e845dfb\", \"wm_unittests\": \"6327b4452d9293c0f9a08fbb59a5edfbb479503b\", \"wtf_unittests\": \"5409ba3808beba4018ba95fef516180831df8720\", \"zlib_unittests\": \"ee0bbbcd7952f3ab36bc94006a1536ca4e741575\", \"zucchini_unittests\": \"245349eaafcab3ca1afee1bfc7607b23fc73e1ac\"}}, \"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-8-2xaw\"], \"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\": [\"5744-5c0a4b4\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- Capture_Monitor/ECKEncryptedMediaOutputProtectionTest.BeforeMediaKeys/0\\n\\n- FormAutofillTest.FillFormWithPlaceholderValues\\n\\n- PasswordAutofillAgentTest.SendPasswordFormsTest_CannotCreatePasswordForm\\n\\n- ChromeContentRendererClientSearchBoxTest.RewriteThumbnailURL\\n\\n- ...6 more failure(s) (10 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615198353857792",
"status": "COMPLETED",
"status_changed_ts": "1615198354347682",
"tags": [
"build_address:luci.chromium.ci/win-asan/17700",
"builder:win-asan",
"buildset:commit/git/a533d5f821569de62406636d12f3d4542d92e7fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a533d5f821569de62406636d12f3d4542d92e7fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018765551279472096",
"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/8853335489357497312/+/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:522a247b10e5c810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615201739603146",
"url": "https://ci.chromium.org/b/8853335489357497312",
"utcnow_ts": "1615201740026048"
}
}
[11:09:00.155] Invocation finished in 56m29.891933627s with status FAILED