[00:16:20.184] New invocation is queued and will start shortly
[00:16:21.325] Starting the invocation (attempt 1)
[00:16:21.367] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:16:21.367] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:16:21.367] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028406337382809360",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028406337382809360",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"gitiles_ref:refs/heads/master"
]
}
[00:16:22.872] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606004181526423",
"id": "8862976275310770112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606004182647112",
"tags": [
"build_address:luci.chromium.ci/win-asan/15769",
"builder:win-asan",
"buildset:commit/git/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028406337382809360",
"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/8862976275310770112/+/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": "1606004182647973",
"url": "https://ci.chromium.org/b/8862976275310770112",
"utcnow_ts": "1606004182861754"
}
}
[00:16:22.872] Task URL: https://ci.chromium.org/b/8862976275310770112
[00:16:22.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:2:0) after 1m0s
[00:16:24.480] Received PubSub notification, asking Buildbucket for the build status
[00:16:24.557] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:22.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:2:0)
[00:17:22.956] Timer tick, asking Buildbucket for the build status
[00:17:23.068] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:23.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:4:0) after 1m0s
[00:18:23.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:4:0)
[00:18:23.122] Timer tick, asking Buildbucket for the build status
[00:18:23.244] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:23.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:5:0) after 1m0s
[00:19:23.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:5:0)
[00:19:23.296] Timer tick, asking Buildbucket for the build status
[00:19:23.795] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:23.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:6:0) after 1m0s
[00:20:23.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:6:0)
[00:20:23.815] Timer tick, asking Buildbucket for the build status
[00:20:23.929] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:23.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:7:0) after 1m0s
[00:21:23.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:7:0)
[00:21:23.975] Timer tick, asking Buildbucket for the build status
[00:21:24.100] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:24.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:8:0) after 1m0s
[00:22:24.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:8:0)
[00:22:24.185] Timer tick, asking Buildbucket for the build status
[00:22:24.290] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:24.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:9:0) after 1m0s
[00:23:24.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:9:0)
[00:23:24.262] Timer tick, asking Buildbucket for the build status
[00:23:24.323] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:24.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:10:0) after 1m0s
[00:24:24.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:10:0)
[00:24:24.342] Timer tick, asking Buildbucket for the build status
[00:24:24.494] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:24.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:11:0) after 1m0s
[00:25:24.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:11:0)
[00:25:24.475] Timer tick, asking Buildbucket for the build status
[00:25:24.584] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:12:0) after 1m0s
[00:26:24.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:12:0)
[00:26:24.589] Timer tick, asking Buildbucket for the build status
[00:26:24.686] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:24.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:13:0) after 1m0s
[00:27:24.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:13:0)
[00:27:24.738] Timer tick, asking Buildbucket for the build status
[00:27:24.854] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:24.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:14:0) after 1m0s
[00:28:24.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:14:0)
[00:28:24.874] Timer tick, asking Buildbucket for the build status
[00:28:25.703] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:15:0) after 1m0s
[00:29:25.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:15:0)
[00:29:25.723] Timer tick, asking Buildbucket for the build status
[00:29:25.852] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:25.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:16:0) after 1m0s
[00:30:25.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:16:0)
[00:30:25.905] Timer tick, asking Buildbucket for the build status
[00:30:26.075] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:26.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:17:0) after 1m0s
[00:31:26.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:17:0)
[00:31:26.099] Timer tick, asking Buildbucket for the build status
[00:31:26.228] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:18:0) after 1m0s
[00:32:26.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:18:0)
[00:32:26.249] Timer tick, asking Buildbucket for the build status
[00:32:27.956] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:27.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:19:0) after 1m0s
[00:33:28.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:19:0)
[00:33:28.010] Timer tick, asking Buildbucket for the build status
[00:33:28.115] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:28.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:20:0) after 1m0s
[00:34:28.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:20:0)
[00:34:28.161] Timer tick, asking Buildbucket for the build status
[00:34:28.268] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:28.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:21:0) after 1m0s
[00:35:28.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:21:0)
[00:35:28.323] Timer tick, asking Buildbucket for the build status
[00:35:28.797] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:28.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:22:0) after 1m0s
[00:36:28.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:22:0)
[00:36:28.820] Timer tick, asking Buildbucket for the build status
[00:36:28.921] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:28.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:23:0) after 1m0s
[00:37:28.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:23:0)
[00:37:28.928] Timer tick, asking Buildbucket for the build status
[00:37:29.062] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:29.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:24:0) after 1m0s
[00:38:29.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:24:0)
[00:38:29.118] Timer tick, asking Buildbucket for the build status
[00:38:29.233] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:29.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:25:0) after 1m0s
[00:39:29.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:25:0)
[00:39:29.352] Timer tick, asking Buildbucket for the build status
[00:39:29.490] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:29.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:26:0) after 1m0s
[00:40:29.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:26:0)
[00:40:29.518] Timer tick, asking Buildbucket for the build status
[00:40:29.585] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:29.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:27:0) after 1m0s
[00:41:29.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:27:0)
[00:41:29.648] Timer tick, asking Buildbucket for the build status
[00:41:29.782] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:29.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:28:0) after 1m0s
[00:42:29.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:28:0)
[00:42:29.805] Timer tick, asking Buildbucket for the build status
[00:42:29.871] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:29.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:29:0) after 1m0s
[00:43:29.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:29:0)
[00:43:29.912] Timer tick, asking Buildbucket for the build status
[00:43:29.979] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:29.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:30:0) after 1m0s
[00:44:30.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:30:0)
[00:44:30.013] Timer tick, asking Buildbucket for the build status
[00:44:30.100] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:30.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:31:0) after 1m0s
[00:45:30.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:31:0)
[00:45:30.121] Timer tick, asking Buildbucket for the build status
[00:45:30.195] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:30.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:32:0) after 1m0s
[00:46:30.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:32:0)
[00:46:30.233] Timer tick, asking Buildbucket for the build status
[00:46:30.334] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:30.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:33:0) after 1m0s
[00:47:30.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:33:0)
[00:47:30.365] Timer tick, asking Buildbucket for the build status
[00:47:30.650] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:30.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:34:0) after 1m0s
[00:48:31.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:34:0)
[00:48:31.075] Timer tick, asking Buildbucket for the build status
[00:48:31.160] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:31.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:35:0) after 1m0s
[00:49:31.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:35:0)
[00:49:31.338] Timer tick, asking Buildbucket for the build status
[00:49:31.561] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:31.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:36:0) after 1m0s
[00:50:31.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:36:0)
[00:50:31.644] Timer tick, asking Buildbucket for the build status
[00:50:31.751] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:31.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:37:0) after 1m0s
[00:51:31.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:37:0)
[00:51:31.869] Timer tick, asking Buildbucket for the build status
[00:51:31.997] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:31.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:38:0) after 1m0s
[00:52:32.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:38:0)
[00:52:32.078] Timer tick, asking Buildbucket for the build status
[00:52:33.500] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:33.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:39:0) after 1m0s
[00:53:33.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:39:0)
[00:53:33.750] Timer tick, asking Buildbucket for the build status
[00:53:34.174] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:34.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:40:0) after 1m0s
[00:54:34.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:40:0)
[00:54:34.363] Timer tick, asking Buildbucket for the build status
[00:54:34.456] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:34.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:41:0) after 1m0s
[00:55:34.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:41:0)
[00:55:34.529] Timer tick, asking Buildbucket for the build status
[00:55:34.680] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:34.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:42:0) after 1m0s
[00:56:34.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:42:0)
[00:56:34.725] Timer tick, asking Buildbucket for the build status
[00:56:34.839] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:34.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:43:0) after 1m0s
[00:57:34.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:43:0)
[00:57:34.853] Timer tick, asking Buildbucket for the build status
[00:57:34.992] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:34.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:44:0) after 1m0s
[00:58:35.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:44:0)
[00:58:35.015] Timer tick, asking Buildbucket for the build status
[00:58:35.135] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:35.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:45:0) after 1m0s
[00:59:35.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:45:0)
[00:59:35.191] Timer tick, asking Buildbucket for the build status
[00:59:35.259] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:35.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:46:0) after 1m0s
[01:00:35.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:46:0)
[01:00:35.301] Timer tick, asking Buildbucket for the build status
[01:00:35.614] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:35.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:47:0) after 1m0s
[01:01:35.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:47:0)
[01:01:35.693] Timer tick, asking Buildbucket for the build status
[01:01:35.878] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:35.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:48:0) after 1m0s
[01:02:35.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:48:0)
[01:02:35.924] Timer tick, asking Buildbucket for the build status
[01:02:36.004] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:36.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:49:0) after 1m0s
[01:03:36.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:49:0)
[01:03:36.010] Timer tick, asking Buildbucket for the build status
[01:03:36.089] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:36.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:50:0) after 1m0s
[01:04:36.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:50:0)
[01:04:36.127] Timer tick, asking Buildbucket for the build status
[01:04:36.202] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:36.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:51:0) after 1m0s
[01:05:36.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:51:0)
[01:05:36.207] Timer tick, asking Buildbucket for the build status
[01:05:38.279] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:38.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:52:0) after 1m0s
[01:06:38.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:52:0)
[01:06:38.300] Timer tick, asking Buildbucket for the build status
[01:06:38.418] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:38.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:53:0) after 1m0s
[01:07:38.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:53:0)
[01:07:38.435] Timer tick, asking Buildbucket for the build status
[01:07:38.504] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:38.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:54:0) after 1m0s
[01:08:38.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:54:0)
[01:08:38.523] Timer tick, asking Buildbucket for the build status
[01:08:38.608] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:38.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:55:0) after 1m0s
[01:09:38.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:55:0)
[01:09:38.640] Timer tick, asking Buildbucket for the build status
[01:09:38.838] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:38.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:56:0) after 1m0s
[01:10:39.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:56:0)
[01:10:39.004] Timer tick, asking Buildbucket for the build status
[01:10:39.073] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:39.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:57:0) after 1m0s
[01:11:39.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:57:0)
[01:11:39.174] Timer tick, asking Buildbucket for the build status
[01:11:39.575] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:39.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:58:0) after 1m0s
[01:12:39.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:58:0)
[01:12:39.725] Timer tick, asking Buildbucket for the build status
[01:12:39.783] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:39.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:59:0) after 1m0s
[01:13:39.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:59:0)
[01:13:39.919] Timer tick, asking Buildbucket for the build status
[01:13:40.070] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:40.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:60:0) after 1m0s
[01:14:40.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:60:0)
[01:14:40.105] Timer tick, asking Buildbucket for the build status
[01:14:40.182] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:40.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:61:0) after 1m0s
[01:15:40.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:61:0)
[01:15:40.264] Timer tick, asking Buildbucket for the build status
[01:15:40.349] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:40.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:62:0) after 1m0s
[01:16:40.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:62:0)
[01:16:40.470] Timer tick, asking Buildbucket for the build status
[01:16:40.600] Build 8862976275310770112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:40.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028406337382809360:63:0) after 1m0s
[01:16:47.216] Received PubSub notification, asking Buildbucket for the build status
[01:16:47.410] Build 8862976275310770112: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:16:47.410] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606007805856791",
"created_by": "project:chromium",
"created_ts": "1606004181526423",
"failure_reason": "BUILD_FAILURE",
"id": "8862976275310770112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\"}}",
"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/3\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/2\\n\\n- ...11 more failure(s) (15 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\": \"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15769, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"e51c9068a84f14e42157c097eb764ec3009e0e54\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\", \"got_revision_cp\": \"refs/heads/master@{#830013}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"22f2e5ef66ed5c820532f27b141096d3ddb1966e\", \"got_v8_revision_cp\": \"refs/heads/8.9.31@{#1}\", \"got_webrtc_revision\": \"7d75f2ca782f38006bb65bc67533e582190454e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32659}\", \"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\": \"b03f591cab107e0d2cc6c11b94030784f1d7d0e6\", \"swarm_hashes_refs/heads/master(at){#830013}_without_patch\": {\"absl_hardening_tests\": \"03f8cf3a2f4169627e0ed28ba69c5c84c44e9bca\", \"accessibility_unittests\": \"fb53dc171b0a719d0aab3c657cd47e0319bf9b4f\", \"angle_unittests\": \"61d00a2ca7432bf2f24d1cfe2e38adfe0b5dada6\", \"app_shell_unittests\": \"32122881fce7d797d393b726c0fc1c0b2467862b\", \"aura_unittests\": \"7f3e44ec9a4f08c3446356586239fe6c4c54fe35\", \"base_unittests\": \"3fa0baf0323929317c0d01e7bdc2ba86f540b31a\", \"base_util_unittests\": \"812836bc6dd6835f64320125857ead7b6fcb58da\", \"blink_common_unittests\": \"adc22442737a39f7feb1177029deaec9c9f16104\", \"blink_fuzzer_unittests\": \"1aacdd5d8440ac920addcd6b1a252cb4ab7e1cb2\", \"blink_heap_unittests\": \"b294e8893d416465bf3b2fbfec08638cb0d337c4\", \"blink_platform_unittests\": \"200234b3e140efc01f28f4b5e88a4f3842ccb447\", \"blink_unittests\": \"d37be9f2b5832f14dcc0aa487bf42216b3529b63\", \"boringssl_crypto_tests\": \"437c75175903f17674a9426a491631b4128312e4\", \"boringssl_ssl_tests\": \"f4ff432e1af43e5acb487de694833bb9c814729b\", \"browser_switcher_bho_unittests\": \"919516cdd26c2924eef5e801aba557510c19e98d\", \"browser_tests\": \"fb3bc47a13f0a67795d7a197692376ee570c1bee\", \"capture_unittests\": \"c59e2862d479d275ee14875e0b1361cda16f9131\", \"cast_unittests\": \"2e81114622f67db4173bd0819448ed199add6ea0\", \"cc_unittests\": \"d42a98bbc33f8d3965b854c372205943f0863ebf\", \"chrome_app_unittests\": \"552b4c22d263bf773c5dedc503834e3b419f5fa4\", \"chrome_cleaner_unittests\": \"7b80ca66f96ea4228110f27f40ec6928a7e0c0ca\", \"chrome_elf_unittests\": \"06d37afff7f3f026839d019ad513129fcaad426d\", \"chromedriver_unittests\": \"f5ea3a58176f8e93189aa35c5ae6c969eceb1626\", \"components_browsertests\": \"04f6af81dd5976f7bfeb1098e12dce2bcca54e54\", \"components_unittests\": \"845b5a38d1b5cd39ee3041d3ef5388084ff84aa7\", \"compositor_unittests\": \"d5129f93580bd6a52c4b28e7323051035dc05ab4\", \"content_browsertests\": \"e31dbf065d1346b16de6a2ce32d652984d92f1e9\", \"content_unittests\": \"3cc02b5ed66fc562e18b1e7a640274a87a5953ed\", \"courgette_unittests\": \"a17f5074c9c443370901ebbd313a73fad4f2232d\", \"crashpad_tests\": \"2fa8caccdc442fd1d58e8af08fc7595ed23482c8\", \"cronet_tests\": \"64a1a87b84d94ba28aa36b43e4349cb99bb90ceb\", \"cronet_unittests\": \"77db119592d5c1ef68e9c029cfddd9430a31f9f6\", \"crypto_unittests\": \"3da2dbc00740440fb318d4c7ff7565d884f2c7d4\", \"delayloads_unittests\": \"56c36bd4648e284909c6334aaddcb94be85bf9a0\", \"device_unittests\": \"b20cb146188d38e07c7cbb4f78a75f7536254792\", \"display_unittests\": \"0e7859cfabf5c929767bb12e87ee08954c1ffd2f\", \"elevation_service_unittests\": \"fe4fee2a7e989746ce1db1016e8b4170d549324c\", \"events_unittests\": \"15216aaf8975385acca07773566d8205a09513d6\", \"extensions_browsertests\": \"4e5656ba21000beb1cb1fee6e2fed6c8a2cf1fb6\", \"extensions_unittests\": \"35ba89b48af4e24b5bb6f1ff86650d9a53136e94\", \"filesystem_service_unittests\": \"fdac573d1382394d2f151c6d3bfbeb63e15a0cc3\", \"gcm_unit_tests\": \"f57052a52de0643e7f742d3751f42fd1513c1717\", \"gcp_unittests\": \"ef90d350489051a6ec137ee8479d6d846eb3a332\", \"gfx_unittests\": \"d4ca301da81f9aae8f2a17134575b0de28a7955a\", \"gin_unittests\": \"f3c145ba478a44a5a280790d3f2f8f22aa41d9e1\", \"google_apis_unittests\": \"8f85a3448b172e3a4a8e47a1bea93f3abbf09a66\", \"gpu_unittests\": \"600b8d882b615314b56fa3dd8ea9e11a4f966058\", \"gwp_asan_unittests\": \"d089adbe34f9eb3581284df92e7c9b9cae7bf80e\", \"headless_browsertests\": \"b74a24cbb2b7db4ee2479a188829d6d5cfb29f58\", \"headless_unittests\": \"38c1e6ae2168c223316fc15dd0392f486e25b88a\", \"install_static_unittests\": \"2edf1b7d23afcb4022d3824355ed553468a3e500\", \"installer_util_unittests\": \"6b209714f6e8db6dce628df252940b3c17d83457\", \"interactive_ui_tests\": \"d66fd3891e27d3e1c54af82a80a0173c82e024cc\", \"ipc_tests\": \"8a91f2ffb2683809f4c32af4109fa87e6e4cee65\", \"jingle_unittests\": \"c724bb89720f07bd799734ae38f279747592896d\", \"latency_unittests\": \"1104431caf1173a54aa4a519a628abb607982b19\", \"libjingle_xmpp_unittests\": \"572ece09f722d4c605e3b5329e22992e71ddf109\", \"liburlpattern_unittests\": \"4d9b160b32a730f6228271723d31ba3533ea8a90\", \"media_blink_unittests\": \"3438c5f654e9c8e42a786806ebf6e4edbdf59914\", \"media_unittests\": \"beafab018506e52496a18445730b1a92300f4e1b\", \"message_center_unittests\": \"ae97a484f5b257d320074647f185f438175d5fc0\", \"midi_unittests\": \"d1c87dca65125232d5addd6c538d49aa18b2b6dc\", \"mojo_core_unittests\": \"475f21aeb586ace23eee0c18ba22b4aafbea0bc3\", \"mojo_unittests\": \"95fe0fda746f2a7fb6ae4eee22844ad6975ec159\", \"nacl_loader_unittests\": \"cdb5ba9dd1906fc3015f463ef81bbb1079867160\", \"native_theme_unittests\": \"ca5738b52eba8b510209eb143e06c9c2b0991998\", \"net_unittests\": \"3cd506f18c18a86a4994dbfe009d8c251640b384\", \"notification_helper_unittests\": \"60dec7b0872e190e5c23e980493d355d9601794d\", \"pdf_unittests\": \"f801d2ea8b56cd6703368d01f1d977fa89afa6a2\", \"perfetto_unittests\": \"82bd99ddee7719e34966774e38dc3b74958506d3\", \"ppapi_unittests\": \"da99c3159c5fc243f5f6de5b2e8f974c6213739b\", \"printing_unittests\": \"9f1492dfd85b14a4c1274e914e554430dd6e94a2\", \"remoting_unittests\": \"4483a6fa7ae3f22b904af6f1f85412396bd22150\", \"sbox_integration_tests\": \"e6bd515c87268308824a2c219974ac6df2d152d1\", \"sbox_unittests\": \"fb25190ce879ae23856e8fa9ec2df388ea4a81f0\", \"sbox_validation_tests\": \"73711daf6e4bee6a49876d8236a80596d9026575\", \"service_manager_unittests\": \"8a2b0f87cdf0eae2fd15ba3d92035ea7cf208c55\", \"services_unittests\": \"64e8ac9dda23b8c225c592e90ff93c0e58a5f73a\", \"setup_unittests\": \"bb2ca24c54cac58f02caed9ef369d2f3543b6eb8\", \"shell_dialogs_unittests\": \"0c3e6c82a3ab78a4b9bd98385581b735936f9cbe\", \"skia_unittests\": \"23f518601809eec29f28ebf03b115857d16e4f55\", \"snapshot_unittests\": \"e04ae10d0e16b101dc24d6bbdb50df90cb781fa1\", \"sql_unittests\": \"b758c138cfee371b392954b82b108423636c39bc\", \"storage_unittests\": \"1e64a290d6a92bb825137fd4cd3a23895bf15394\", \"sync_integration_tests\": \"75f24356619f66e4c6477f721cf59578b16b2f7d\", \"traffic_annotation_auditor_unittests\": \"f47a08f7c985d97872e41dcc546da1e45a12b3a7\", \"ui_base_unittests\": \"8d9d0c42bc6315fd5f95dc4eb8df2e5174c64a2c\", \"ui_touch_selection_unittests\": \"10dc99cf467af7e1afaa6969936270d1088c431b\", \"unit_tests\": \"5f5910ad1483dbf4f86a04214c4be8cb8878f1dc\", \"updater_tests\": \"cd2a06ef8482e145639538fdfdf3c49283186b8b\", \"url_unittests\": \"6ad24ee46ed673531cf4d3fba2020f90e7f10499\", \"views_unittests\": \"73c525b19fabe009d7cd60e47d4468379f0fb452\", \"viz_unittests\": \"1dd2bfe1cb1cdda8b730ddb533f2a93de92a5e6f\", \"vr_common_unittests\": \"976612f7a34d303f0dd951f49940e080839e980b\", \"vr_pixeltests\": \"cdcd95ae1a614581232fa6ac15913bae18141d11\", \"weblayer_browsertests\": \"0707f2af19ef824049ab21be3a844be352f4c146\", \"weblayer_unittests\": \"c4bcf0313d7af841b1d4fe18859fe9522a74f17e\", \"wm_unittests\": \"61c952ad5a3d6aa74441fb99a98e4b44b215abf9\", \"wtf_unittests\": \"9b3a592a33ef0bffcaf4d28dc25a9f976daaf642\", \"zlib_unittests\": \"ba6da342d7ae880f9c35d222d5c5daa0c2a982a3\", \"zucchini_unittests\": \"75335a4920051e8a5388fc68fa6c32da0ae47dce\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xoby\"], \"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\": [\"5637-4d8b832\"], \"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/3\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/2\\n\\n- ...11 more failure(s) (15 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606004183706812",
"status": "COMPLETED",
"status_changed_ts": "1606004184168323",
"tags": [
"build_address:luci.chromium.ci/win-asan/15769",
"builder:win-asan",
"buildset:commit/git/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03f591cab107e0d2cc6c11b94030784f1d7d0e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028406337382809360",
"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/8862976275310770112/+/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:5006209771af0510",
"user_agent:luci-scheduler"
],
"updated_ts": "1606007806789306",
"url": "https://ci.chromium.org/b/8862976275310770112",
"utcnow_ts": "1606007807382975"
}
}
[01:16:47.410] Invocation finished in 1h0m27.244126994s with status FAILED