[04:45:04.787] New invocation is queued and will start shortly
[04:45:06.056] Starting the invocation (attempt 1)
[04:45:06.091] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:45:06.091] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:45:06.092] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027121071981879664",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027121071981879664",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"gitiles_ref:refs/heads/master"
]
}
[04:45:07.320] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607229906188345",
"id": "8861691009846777728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607229907005550",
"tags": [
"build_address:luci.chromium.ci/win-asan/16055",
"builder:win-asan",
"buildset:commit/git/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027121071981879664",
"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/8861691009846777728/+/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": "1607229907005736",
"url": "https://ci.chromium.org/b/8861691009846777728",
"utcnow_ts": "1607229907279306"
}
}
[04:45:07.320] Task URL: https://ci.chromium.org/b/8861691009846777728
[04:45:07.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:2:0) after 1m0s
[04:45:09.059] Received PubSub notification, asking Buildbucket for the build status
[04:45:09.186] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:07.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:2:0)
[04:46:07.408] Timer tick, asking Buildbucket for the build status
[04:46:07.574] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:07.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:4:0) after 1m0s
[04:47:07.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:4:0)
[04:47:07.581] Timer tick, asking Buildbucket for the build status
[04:47:07.872] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:07.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:5:0) after 1m0s
[04:48:07.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:5:0)
[04:48:07.856] Timer tick, asking Buildbucket for the build status
[04:48:08.302] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:6:0) after 1m0s
[04:49:08.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:6:0)
[04:49:08.333] Timer tick, asking Buildbucket for the build status
[04:49:08.545] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:08.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:7:0) after 1m0s
[04:50:08.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:7:0)
[04:50:08.614] Timer tick, asking Buildbucket for the build status
[04:50:08.961] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:08.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:8:0) after 1m0s
[04:51:09.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:8:0)
[04:51:09.057] Timer tick, asking Buildbucket for the build status
[04:51:09.364] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:09.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:9:0) after 1m0s
[04:52:09.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:9:0)
[04:52:09.432] Timer tick, asking Buildbucket for the build status
[04:52:09.486] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:09.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:10:0) after 1m0s
[04:53:09.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:10:0)
[04:53:09.614] Timer tick, asking Buildbucket for the build status
[04:53:10.089] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:10.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:11:0) after 1m0s
[04:54:10.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:11:0)
[04:54:10.450] Timer tick, asking Buildbucket for the build status
[04:54:10.507] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:10.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:12:0) after 1m0s
[04:55:10.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:12:0)
[04:55:10.583] Timer tick, asking Buildbucket for the build status
[04:55:10.721] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:10.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:13:0) after 1m0s
[04:56:10.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:13:0)
[04:56:10.859] Timer tick, asking Buildbucket for the build status
[04:56:11.063] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:11.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:14:0) after 1m0s
[04:57:11.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:14:0)
[04:57:11.109] Timer tick, asking Buildbucket for the build status
[04:57:11.264] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:11.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:15:0) after 1m0s
[04:58:11.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:15:0)
[04:58:11.299] Timer tick, asking Buildbucket for the build status
[04:58:11.378] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:11.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:16:0) after 1m0s
[04:59:11.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:16:0)
[04:59:11.394] Timer tick, asking Buildbucket for the build status
[04:59:11.513] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:11.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:17:0) after 1m0s
[05:00:11.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:17:0)
[05:00:11.557] Timer tick, asking Buildbucket for the build status
[05:00:11.663] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:11.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:18:0) after 1m0s
[05:01:11.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:18:0)
[05:01:11.753] Timer tick, asking Buildbucket for the build status
[05:01:12.015] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:12.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:19:0) after 1m0s
[05:02:12.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:19:0)
[05:02:12.001] Timer tick, asking Buildbucket for the build status
[05:02:12.197] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:12.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:20:0) after 1m0s
[05:03:12.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:20:0)
[05:03:12.229] Timer tick, asking Buildbucket for the build status
[05:03:12.382] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:12.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:21:0) after 1m0s
[05:04:12.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:21:0)
[05:04:12.392] Timer tick, asking Buildbucket for the build status
[05:04:12.628] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:12.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:22:0) after 1m0s
[05:05:12.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:22:0)
[05:05:12.662] Timer tick, asking Buildbucket for the build status
[05:05:13.802] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:13.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:23:0) after 1m0s
[05:06:13.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:23:0)
[05:06:13.840] Timer tick, asking Buildbucket for the build status
[05:06:13.941] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:13.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:24:0) after 1m0s
[05:07:13.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:24:0)
[05:07:13.999] Timer tick, asking Buildbucket for the build status
[05:07:14.564] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:14.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:25:0) after 1m0s
[05:08:14.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:25:0)
[05:08:14.638] Timer tick, asking Buildbucket for the build status
[05:08:16.050] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:16.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:26:0) after 1m0s
[05:09:16.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:26:0)
[05:09:16.143] Timer tick, asking Buildbucket for the build status
[05:09:17.593] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:17.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:27:0) after 1m0s
[05:10:17.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:27:0)
[05:10:17.616] Timer tick, asking Buildbucket for the build status
[05:10:17.756] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:17.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:28:0) after 1m0s
[05:11:17.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:28:0)
[05:11:17.791] Timer tick, asking Buildbucket for the build status
[05:11:17.897] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:17.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:29:0) after 1m0s
[05:12:17.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:29:0)
[05:12:17.916] Timer tick, asking Buildbucket for the build status
[05:12:18.244] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:18.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:30:0) after 1m0s
[05:13:18.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:30:0)
[05:13:18.263] Timer tick, asking Buildbucket for the build status
[05:13:18.497] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:18.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:31:0) after 1m0s
[05:14:18.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:31:0)
[05:14:18.516] Timer tick, asking Buildbucket for the build status
[05:14:18.607] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:18.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:32:0) after 1m0s
[05:15:18.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:32:0)
[05:15:18.686] Timer tick, asking Buildbucket for the build status
[05:15:18.816] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:18.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:33:0) after 1m0s
[05:16:18.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:33:0)
[05:16:18.863] Timer tick, asking Buildbucket for the build status
[05:16:19.180] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:19.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:34:0) after 1m0s
[05:17:19.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:34:0)
[05:17:19.253] Timer tick, asking Buildbucket for the build status
[05:17:19.366] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:19.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:35:0) after 1m0s
[05:18:19.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:35:0)
[05:18:19.421] Timer tick, asking Buildbucket for the build status
[05:18:19.559] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:19.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:36:0) after 1m0s
[05:19:19.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:36:0)
[05:19:19.563] Timer tick, asking Buildbucket for the build status
[05:19:19.778] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:19.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:37:0) after 1m0s
[05:20:19.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:37:0)
[05:20:19.865] Timer tick, asking Buildbucket for the build status
[05:20:20.061] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:20.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:38:0) after 1m0s
[05:21:20.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:38:0)
[05:21:20.069] Timer tick, asking Buildbucket for the build status
[05:21:20.223] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:20.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:39:0) after 1m0s
[05:22:20.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:39:0)
[05:22:20.259] Timer tick, asking Buildbucket for the build status
[05:22:20.835] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:20.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:40:0) after 1m0s
[05:23:20.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:40:0)
[05:23:20.940] Timer tick, asking Buildbucket for the build status
[05:23:21.298] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:21.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:41:0) after 1m0s
[05:24:21.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:41:0)
[05:24:21.322] Timer tick, asking Buildbucket for the build status
[05:24:21.859] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:21.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:42:0) after 1m0s
[05:25:21.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:42:0)
[05:25:21.954] Timer tick, asking Buildbucket for the build status
[05:25:22.367] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:22.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:43:0) after 1m0s
[05:26:22.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:43:0)
[05:26:22.414] Timer tick, asking Buildbucket for the build status
[05:26:22.757] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:22.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:44:0) after 1m0s
[05:27:22.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:44:0)
[05:27:22.807] Timer tick, asking Buildbucket for the build status
[05:27:22.899] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:22.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:45:0) after 1m0s
[05:28:22.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:45:0)
[05:28:22.959] Timer tick, asking Buildbucket for the build status
[05:28:23.370] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:23.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:46:0) after 1m0s
[05:29:23.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:46:0)
[05:29:23.527] Timer tick, asking Buildbucket for the build status
[05:29:23.758] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:23.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:47:0) after 1m0s
[05:30:23.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:47:0)
[05:30:23.801] Timer tick, asking Buildbucket for the build status
[05:30:23.923] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:23.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:48:0) after 1m0s
[05:31:23.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:48:0)
[05:31:23.947] Timer tick, asking Buildbucket for the build status
[05:31:24.388] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:24.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:49:0) after 1m0s
[05:32:24.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:49:0)
[05:32:24.412] Timer tick, asking Buildbucket for the build status
[05:32:24.547] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:24.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:50:0) after 1m0s
[05:33:24.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:50:0)
[05:33:24.571] Timer tick, asking Buildbucket for the build status
[05:33:24.705] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:24.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:51:0) after 1m0s
[05:34:24.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:51:0)
[05:34:24.817] Timer tick, asking Buildbucket for the build status
[05:34:28.497] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:28.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:52:0) after 1m0s
[05:35:28.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:52:0)
[05:35:28.565] Timer tick, asking Buildbucket for the build status
[05:35:28.751] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:28.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:53:0) after 1m0s
[05:36:28.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:53:0)
[05:36:28.793] Timer tick, asking Buildbucket for the build status
[05:36:28.930] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:28.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:54:0) after 1m0s
[05:37:29.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:54:0)
[05:37:29.021] Timer tick, asking Buildbucket for the build status
[05:37:29.457] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:29.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:55:0) after 1m0s
[05:38:29.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:55:0)
[05:38:29.540] Timer tick, asking Buildbucket for the build status
[05:38:29.700] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:29.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:56:0) after 1m0s
[05:39:29.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:56:0)
[05:39:29.749] Timer tick, asking Buildbucket for the build status
[05:39:30.183] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:30.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:57:0) after 1m0s
[05:40:30.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:57:0)
[05:40:30.302] Timer tick, asking Buildbucket for the build status
[05:40:30.510] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:30.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:58:0) after 1m0s
[05:41:30.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:58:0)
[05:41:30.605] Timer tick, asking Buildbucket for the build status
[05:41:30.688] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:30.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:59:0) after 1m0s
[05:42:30.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:59:0)
[05:42:30.759] Timer tick, asking Buildbucket for the build status
[05:42:30.826] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:30.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:60:0) after 1m0s
[05:43:30.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:60:0)
[05:43:30.919] Timer tick, asking Buildbucket for the build status
[05:43:31.026] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:31.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:61:0) after 1m0s
[05:44:31.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:61:0)
[05:44:31.013] Timer tick, asking Buildbucket for the build status
[05:44:34.032] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:34.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:62:0) after 1m0s
[05:45:34.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:62:0)
[05:45:34.077] Timer tick, asking Buildbucket for the build status
[05:45:34.456] Build 8861691009846777728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:34.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027121071981879664:63:0) after 1m0s
[05:46:22.464] Received PubSub notification, asking Buildbucket for the build status
[05:46:22.544] Build 8861691009846777728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:46:22.544] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607233580475182",
"created_by": "project:chromium",
"created_ts": "1607229906188345",
"id": "8861691009846777728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-jjte\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16055, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\", \"got_revision_cp\": \"refs/heads/master@{#834055}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7c84426d2abe231f3087a2942fd831137eb3b0aa\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"d5d882f6fa29443886b050fcdbd744e2ef5b56e2\", \"swarm_hashes_refs/heads/master(at){#834055}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"f6ecbfbbe6177e1e2bb600a1a89f7347a82a501f\", \"angle_unittests\": \"b1485a29af17d361aec21fe0249230f2f510b4b0\", \"app_shell_unittests\": \"e0c02b7bdc1ed7527958165a50754a69c5889a65\", \"aura_unittests\": \"9b48052451a07fc1accf544ed22d4888b697be6c\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"77b1b04979bc187e44b0ebca363a9f593ec4d6ec\", \"blink_fuzzer_unittests\": \"31664da636dc35ce0a92375a271f90b08076f290\", \"blink_heap_unittests\": \"ebe93f36f762ac9af93a62fef8cebe52c42d0abc\", \"blink_platform_unittests\": \"f567210175a49514b4e9cf0fee415bc37e76af92\", \"blink_unittests\": \"e80820fb908b769d6f9eae352b8d4702facc97e1\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"0a3f22319289506fc6bba0031f0b940c74996af0\", \"capture_unittests\": \"27bedf7e7ca88beb1ddef535392f0eef0ee881f7\", \"cast_unittests\": \"b7c0797e55b26df77833b6e6a7ec0324a204b83e\", \"cc_unittests\": \"cd8af07fa1dde7f8f721f0131f91558a5391da33\", \"chrome_app_unittests\": \"88d04463ae74fcdf525164a94e24450782b29e85\", \"chrome_cleaner_unittests\": \"952c809b2cb0add6dde5945256170b0832daad99\", \"chrome_elf_unittests\": \"5f631304e9b6dbd32893da2c073e3374632cb961\", \"chromedriver_unittests\": \"8d2ea9788db14198b82ce1f6f2b303dbd2d55de0\", \"components_browsertests\": \"7597d8de39b5cdd84ae3d2b7e66f450e715de73f\", \"components_unittests\": \"14fbadc94e9f90185e344e832ab856ade203694f\", \"compositor_unittests\": \"ee47025909b64f8002236981bd7afffd1e1f3ac0\", \"content_browsertests\": \"e6dde8bad3929cb04ea27852c211fe085b3ce7cb\", \"content_unittests\": \"95771d5dd5f8927e9686f386842e773324a83ec7\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"8112add7b59b6c012211608d2f11993aa3b5f39e\", \"cronet_unittests\": \"0c68c70a54366d93a3c361ecac6f603dbbc9bc94\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"0ac8e630560d89e21d1ffc70a698c539f54b582c\", \"device_unittests\": \"96bacf40932ba3ecf7641692ab9f92548f6056d5\", \"display_unittests\": \"aafef6883b6e9187137b4920aac18638d64d9103\", \"elevation_service_unittests\": \"aec79b3cee09d68845ac9e3e2000453a52e5d2bd\", \"events_unittests\": \"cd388d28c53ee40b0c2bd0d849e96d8caddc563b\", \"extensions_browsertests\": \"a8b5f90901916924213eac19cec0e9e9d7c4ec47\", \"extensions_unittests\": \"3b4cd85dacd2ea97913c843b49e76a8e42823b94\", \"filesystem_service_unittests\": \"ffb02b4ef40d92df8f02b3d9018d07672c08369b\", \"gcm_unit_tests\": \"6741fa273b762fdb590d20773ecd5ace1072ec53\", \"gcp_unittests\": \"b12b80faab16a14dad5cfbd752f7a4934e19cf05\", \"gfx_unittests\": \"888a9e69ed9184387480f5562e10bad894aea076\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"7ecf73beaf0f867e054381d77b45611fb9f8bc24\", \"gpu_unittests\": \"b31c75e5c8f8dd39a0395e4b91533b00a2e384c2\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"186c22b0c86db54dcfe2dc9909faea53f3b2655e\", \"headless_unittests\": \"d9b2179b08bcb9187dbb91ed63f30391e9af5e1a\", \"install_static_unittests\": \"ddbdd5849b9a961f1448aaed64a0e13e152514ff\", \"installer_util_unittests\": \"3890209cb46fdeeeb92127fbfa9d3cb9615932aa\", \"interactive_ui_tests\": \"fb5b8a2926be61c7ed1e6e4234e2779518429b32\", \"ipc_tests\": \"28708cda7035455a5b5435d9ad983f268be54ad7\", \"jingle_unittests\": \"6ff329306a16a2e4838f1e7f76c5284438e6553e\", \"latency_unittests\": \"0bebb9f9c1070c05fa44ecc6d42b8a4343fa8962\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"033aa9ce5d66805cc003e3b88d49b5dab805920c\", \"media_unittests\": \"77cec99a6b644fad01ad01e776cca9b33ac836f9\", \"message_center_unittests\": \"29eee922df9505b7b811c0a0c3dcde05f76e3821\", \"midi_unittests\": \"f5119fe998270cf329c66a9380216f5d5de896a7\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"69a83ea54e97dfaefc9a7fdde5ba03962dae0b78\", \"nacl_loader_unittests\": \"b798a7dd7f93a44a738c8c466f79fe8ce99ab0ea\", \"native_theme_unittests\": \"2a808ff3abe47df0c7554daf02339fe9550884a4\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"919639e13ee00ff105a1037a6313d3fa0925ef96\", \"pdf_unittests\": \"6286cce4125c7e709aff4e9e79ba28a2580d5507\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"37378ede05f8b2b7abcebf72df96ac0124c1d608\", \"printing_unittests\": \"d55774c6dd6e21da30d34fe187142bc2951154d9\", \"remoting_unittests\": \"795fa46de0e20ef86ff05d841de2705e5e859382\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"b133d037ba8e17b95cedab26815e744b0c7fb06c\", \"services_unittests\": \"2dd303d34ffe978242e95d83d4044e20855df659\", \"setup_unittests\": \"a6aeecc2802fc2334a4171055bab11e7a9a1b475\", \"shell_dialogs_unittests\": \"afb0fb19179a8eb96ff2e37ca29a029124b91cce\", \"skia_unittests\": \"5ba0a416de55b1cc6f22cd8d08ac74f8482c14d2\", \"snapshot_unittests\": \"6658eda49bef510512277d3eb8ea14f819e5a1ac\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"3a6b055e28b80f547ca9fb129bb53a1d0dad0093\", \"sync_integration_tests\": \"e91a9a60f19ca985c000f87785edef75699d51b2\", \"traffic_annotation_auditor_unittests\": \"2a838919b1dff95eacabb786e1fb70371f6d1645\", \"ui_base_unittests\": \"a13227a8438045426817d09d8fdbd4ea1783d33c\", \"ui_touch_selection_unittests\": \"5714b717033a23792dcd0618f02632814ee44d26\", \"unit_tests\": \"efe75d2de94b6116dbdf8cfdf17703442578fa46\", \"updater_tests\": \"116556903dfe3067b39f72c2ed1c5f08e4764b0b\", \"url_unittests\": \"e8e8b2b3c3b50cc46a85056cd7b30d2a95cc4f7e\", \"views_unittests\": \"065e5568a4c039ff5fff856fcd30922fd48010c7\", \"viz_unittests\": \"8e9832c892b25eb8ab5f5c5dd2184e601ff989a8\", \"vr_common_unittests\": \"4c47716de558f80521e803a747677fab37fe80e1\", \"vr_pixeltests\": \"5d63df3b67e1e963f77f8df0633bb7c09f50b232\", \"weblayer_browsertests\": \"580261582d78d480412cd0acd0dac4513fcfd7b4\", \"weblayer_unittests\": \"8faa6bc9b879a1322a7b4d3545e87bf9e6386aae\", \"wm_unittests\": \"001ef2939ed1ca5ed1ba59cebed2ac62e45bc31b\", \"wtf_unittests\": \"74b10220a757393af5ce1be933bf631032bdbf82\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-jjte\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607229908397443",
"status": "COMPLETED",
"status_changed_ts": "1607233580785527",
"tags": [
"build_address:luci.chromium.ci/win-asan/16055",
"builder:win-asan",
"buildset:commit/git/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d5d882f6fa29443886b050fcdbd744e2ef5b56e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027121071981879664",
"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/8861691009846777728/+/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:504f2fa9ea453e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607233580792055",
"url": "https://ci.chromium.org/b/8861691009846777728",
"utcnow_ts": "1607233582533893"
}
}
[05:46:22.544] Invocation finished in 1h1m17.774530319s with status SUCCEEDED