[02:20:59.462] New invocation is queued and will start shortly
[02:21:00.857] Starting the invocation (attempt 1)
[02:21:00.890] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:21:00.890] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:21:00.890] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023234467703578128",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023234467703578128",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"gitiles_ref:refs/heads/master"
]
}
[02:21:02.226] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610936461092768",
"id": "8857804405332314512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610936461942266",
"tags": [
"build_address:luci.chromium.ci/win-asan/16862",
"builder:win-asan",
"buildset:commit/git/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023234467703578128",
"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/8857804405332314512/+/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": "1610936461942394",
"url": "https://ci.chromium.org/b/8857804405332314512",
"utcnow_ts": "1610936462214193"
}
}
[02:21:02.226] Task URL: https://ci.chromium.org/b/8857804405332314512
[02:21:02.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:2:0) after 1m0s
[02:21:07.319] Received PubSub notification, asking Buildbucket for the build status
[02:21:07.383] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:02.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:2:0)
[02:22:02.273] Timer tick, asking Buildbucket for the build status
[02:22:02.490] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:02.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:4:0) after 1m0s
[02:23:02.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:4:0)
[02:23:02.507] Timer tick, asking Buildbucket for the build status
[02:23:02.789] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:02.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:5:0) after 1m0s
[02:24:02.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:5:0)
[02:24:02.809] Timer tick, asking Buildbucket for the build status
[02:24:02.900] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:6:0) after 1m0s
[02:25:03.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:6:0)
[02:25:03.008] Timer tick, asking Buildbucket for the build status
[02:25:03.280] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:03.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:7:0) after 1m0s
[02:26:03.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:7:0)
[02:26:03.377] Timer tick, asking Buildbucket for the build status
[02:26:03.504] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:03.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:8:0) after 1m0s
[02:27:03.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:8:0)
[02:27:03.647] Timer tick, asking Buildbucket for the build status
[02:27:03.750] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:03.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:9:0) after 1m0s
[02:28:03.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:9:0)
[02:28:03.878] Timer tick, asking Buildbucket for the build status
[02:28:03.945] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:03.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:10:0) after 1m0s
[02:29:04.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:10:0)
[02:29:04.002] Timer tick, asking Buildbucket for the build status
[02:29:04.229] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:04.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:11:0) after 1m0s
[02:30:04.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:11:0)
[02:30:04.256] Timer tick, asking Buildbucket for the build status
[02:30:04.449] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:04.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:12:0) after 1m0s
[02:31:04.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:12:0)
[02:31:04.626] Timer tick, asking Buildbucket for the build status
[02:31:04.810] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:04.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:13:0) after 1m0s
[02:32:04.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:13:0)
[02:32:04.908] Timer tick, asking Buildbucket for the build status
[02:32:05.215] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:05.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:14:0) after 1m0s
[02:33:05.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:14:0)
[02:33:05.233] Timer tick, asking Buildbucket for the build status
[02:33:05.299] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:05.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:15:0) after 1m0s
[02:34:05.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:15:0)
[02:34:05.327] Timer tick, asking Buildbucket for the build status
[02:34:05.730] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:05.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:16:0) after 1m0s
[02:35:05.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:16:0)
[02:35:05.974] Timer tick, asking Buildbucket for the build status
[02:35:06.118] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:06.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:17:0) after 1m0s
[02:36:06.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:17:0)
[02:36:06.354] Timer tick, asking Buildbucket for the build status
[02:36:06.475] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:06.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:18:0) after 1m0s
[02:37:06.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:18:0)
[02:37:06.508] Timer tick, asking Buildbucket for the build status
[02:37:06.743] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:06.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:19:0) after 1m0s
[02:38:06.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:19:0)
[02:38:06.823] Timer tick, asking Buildbucket for the build status
[02:38:06.931] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:06.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:20:0) after 1m0s
[02:39:06.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:20:0)
[02:39:06.954] Timer tick, asking Buildbucket for the build status
[02:39:07.299] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:07.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:21:0) after 1m0s
[02:40:07.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:21:0)
[02:40:07.345] Timer tick, asking Buildbucket for the build status
[02:40:07.841] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:22:0) after 1m0s
[02:41:07.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:22:0)
[02:41:07.902] Timer tick, asking Buildbucket for the build status
[02:41:08.086] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:08.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:23:0) after 1m0s
[02:42:08.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:23:0)
[02:42:08.206] Timer tick, asking Buildbucket for the build status
[02:42:08.325] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:08.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:24:0) after 1m0s
[02:43:08.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:24:0)
[02:43:08.392] Timer tick, asking Buildbucket for the build status
[02:43:08.491] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:08.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:25:0) after 1m0s
[02:44:08.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:25:0)
[02:44:08.599] Timer tick, asking Buildbucket for the build status
[02:44:08.698] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:08.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:26:0) after 1m0s
[02:45:08.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:26:0)
[02:45:08.734] Timer tick, asking Buildbucket for the build status
[02:45:08.861] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:08.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:27:0) after 1m0s
[02:46:09.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:27:0)
[02:46:09.012] Timer tick, asking Buildbucket for the build status
[02:46:09.128] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:09.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:28:0) after 1m0s
[02:47:09.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:28:0)
[02:47:09.247] Timer tick, asking Buildbucket for the build status
[02:47:09.329] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:09.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:29:0) after 1m0s
[02:48:09.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:29:0)
[02:48:09.443] Timer tick, asking Buildbucket for the build status
[02:48:09.522] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:09.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:30:0) after 1m0s
[02:49:09.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:30:0)
[02:49:09.526] Timer tick, asking Buildbucket for the build status
[02:49:09.599] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:09.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:31:0) after 1m0s
[02:50:09.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:31:0)
[02:50:09.666] Timer tick, asking Buildbucket for the build status
[02:50:09.769] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:09.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:32:0) after 1m0s
[02:51:09.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:32:0)
[02:51:09.820] Timer tick, asking Buildbucket for the build status
[02:51:09.925] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:09.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:33:0) after 1m0s
[02:52:10.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:33:0)
[02:52:10.042] Timer tick, asking Buildbucket for the build status
[02:52:10.119] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:34:0) after 1m0s
[02:53:10.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:34:0)
[02:53:10.181] Timer tick, asking Buildbucket for the build status
[02:53:10.272] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:35:0) after 1m0s
[02:54:10.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:35:0)
[02:54:10.392] Timer tick, asking Buildbucket for the build status
[02:54:10.472] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:36:0) after 1m0s
[02:55:10.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:36:0)
[02:55:10.513] Timer tick, asking Buildbucket for the build status
[02:55:10.626] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:10.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:37:0) after 1m0s
[02:56:10.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:37:0)
[02:56:10.644] Timer tick, asking Buildbucket for the build status
[02:56:11.193] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:38:0) after 1m0s
[02:57:11.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:38:0)
[02:57:11.301] Timer tick, asking Buildbucket for the build status
[02:57:11.462] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:11.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:39:0) after 1m0s
[02:58:11.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:39:0)
[02:58:11.482] Timer tick, asking Buildbucket for the build status
[02:58:11.874] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:11.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:40:0) after 1m0s
[02:59:12.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:40:0)
[02:59:12.220] Timer tick, asking Buildbucket for the build status
[02:59:12.352] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:12.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:41:0) after 1m0s
[03:00:12.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:41:0)
[03:00:12.817] Timer tick, asking Buildbucket for the build status
[03:00:12.945] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:12.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:42:0) after 1m0s
[03:01:13.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:42:0)
[03:01:13.117] Timer tick, asking Buildbucket for the build status
[03:01:13.309] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:13.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:43:0) after 1m0s
[03:02:13.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:43:0)
[03:02:13.442] Timer tick, asking Buildbucket for the build status
[03:02:13.513] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:13.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:44:0) after 1m0s
[03:03:13.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:44:0)
[03:03:13.798] Timer tick, asking Buildbucket for the build status
[03:03:14.042] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:14.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:45:0) after 1m0s
[03:04:14.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:45:0)
[03:04:14.104] Timer tick, asking Buildbucket for the build status
[03:04:14.167] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:14.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:46:0) after 1m0s
[03:05:14.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:46:0)
[03:05:14.280] Timer tick, asking Buildbucket for the build status
[03:05:14.518] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:14.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:47:0) after 1m0s
[03:06:14.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:47:0)
[03:06:14.643] Timer tick, asking Buildbucket for the build status
[03:06:14.766] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:14.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:48:0) after 1m0s
[03:07:14.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:48:0)
[03:07:14.919] Timer tick, asking Buildbucket for the build status
[03:07:15.038] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:15.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:49:0) after 1m0s
[03:08:15.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:49:0)
[03:08:15.194] Timer tick, asking Buildbucket for the build status
[03:08:15.582] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:15.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:50:0) after 1m0s
[03:09:15.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:50:0)
[03:09:15.674] Timer tick, asking Buildbucket for the build status
[03:09:15.764] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:15.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:51:0) after 1m0s
[03:10:15.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:51:0)
[03:10:15.966] Timer tick, asking Buildbucket for the build status
[03:10:16.613] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:16.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:52:0) after 1m0s
[03:11:16.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:52:0)
[03:11:16.615] Timer tick, asking Buildbucket for the build status
[03:11:16.825] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:16.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:53:0) after 1m0s
[03:12:16.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:53:0)
[03:12:16.929] Timer tick, asking Buildbucket for the build status
[03:12:16.992] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:16.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:54:0) after 1m0s
[03:13:17.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:54:0)
[03:13:17.038] Timer tick, asking Buildbucket for the build status
[03:13:17.138] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:17.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:55:0) after 1m0s
[03:14:17.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:55:0)
[03:14:17.157] Timer tick, asking Buildbucket for the build status
[03:14:17.258] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:17.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:56:0) after 1m0s
[03:15:18.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:56:0)
[03:15:18.119] Timer tick, asking Buildbucket for the build status
[03:15:18.224] Build 8857804405332314512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:18.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023234467703578128:57:0) after 1m0s
[03:15:57.288] Received PubSub notification, asking Buildbucket for the build status
[03:15:57.390] Build 8857804405332314512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:15:57.391] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610939756137216",
"created_by": "project:chromium",
"created_ts": "1610936461092768",
"id": "8857804405332314512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\"}}",
"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\": \"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\", \"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-9-kl3f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16862, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"dfd9bdfd656762297dbdb8cc6f9e28b84990ea1c\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\", \"got_revision_cp\": \"refs/heads/master@{#844422}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"09729d2ecf49d256f6648494b59076b01786dc51\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33013}\", \"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\": \"63de3bd4a15cc90d0b86d923626e6f133bca7e7c\", \"swarm_hashes_refs/heads/master(at){#844422}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"005ba7bb50ce5407a77257495354e3f65a3561fb\", \"angle_unittests\": \"154d70262a5e4781b0c80ec82d6ec6e04fcd7c9d\", \"app_shell_unittests\": \"1750df96b411ccfd9985aae885bef4be4f5be276\", \"aura_unittests\": \"5cfacee696f17c34f2cd1344e7b230090090e808\", \"base_unittests\": \"37a1d7978705d5e766f6f4a4a7fdd516807120c6\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"f4bb9afd5ae10db6365f52df8f5c295a5aa4fbbf\", \"blink_fuzzer_unittests\": \"a84b8dfba327753a1861dbc9beeb74df61fcb4e4\", \"blink_heap_unittests\": \"fae061b762488fe3d78c06fda5e8f80d2310511a\", \"blink_platform_unittests\": \"4a3d8c7b9907e66714452ecd58b8731065e4ddc2\", \"blink_unittests\": \"d05f471c41b56705314cec7fb9acff3387e98f94\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"b8ec376820997e92262ce24b361f3161625f2e40\", \"capture_unittests\": \"ec499bf387447b2338c3bb823f9261a12ff06de2\", \"cast_unittests\": \"7c22857b09b2a39066c45d374588346b6a01e2b5\", \"cc_unittests\": \"b793c0117c4f7e441c74726c4e078832e35eade6\", \"chrome_app_unittests\": \"c872d27ce785279845b2abc0cfbc82089f267ac6\", \"chrome_cleaner_unittests\": \"90b36eee6d28f202fd231da4f683b51de34e3750\", \"chrome_elf_unittests\": \"d1242541a1cda4bb2f0d4c56199db88ff02a2139\", \"chromedriver_unittests\": \"6b6caeaf4c0955556aa990db3227e7246c94c3ce\", \"components_browsertests\": \"7a3cbd5d94df975b2b2b66b76e69214784c26bc4\", \"components_unittests\": \"f2c3748d0e1b1d197dc8551ab75df432b90b9c5c\", \"compositor_unittests\": \"be908a575cf8c7d97e99e1cae9db70e320b91729\", \"content_browsertests\": \"07b810b100aafe32c76b276dc517ade8d38b5ab5\", \"content_unittests\": \"2cf3eec8dbee1095b6c291bd6f77098cfc7bf6b5\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"2aee18e78ea0acc4b97b78a470524facecfe54c1\", \"cronet_unittests\": \"d3f3007063a2a7c3abc31338230aa16691ad1b12\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"324158960175d8a43fc2f63241dfdeae224674ee\", \"device_unittests\": \"337214edfaa67d036980c115b707d8fc2d1b4bb4\", \"display_unittests\": \"1826b4556f79e085bd7b2e7be4b6d8ee335f51b6\", \"elevation_service_unittests\": \"a15fb883fb8083d2ef70cb780ebaf792eb9441fe\", \"events_unittests\": \"42cec0232c1f04bca8cc05ac4b2f839710e87258\", \"extensions_browsertests\": \"eb3853b1ab8ca4e0207f49f2bf6cfbef2615446b\", \"extensions_unittests\": \"20b9e762978f0efde4b8ff96ffae50475d42672f\", \"filesystem_service_unittests\": \"3bb9ea563f081231d01ac9b4be04c470e2ba3660\", \"gcm_unit_tests\": \"56aaf6e066b6e124e3e9592fb20ad76de72af599\", \"gcp_unittests\": \"9227a5058f5e4be815d891354aa9e7dfa62cd9f6\", \"gfx_unittests\": \"005bb652b0bcf696a5dd01480e5feaeeed79dfca\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"a75be2fa2cdcedbaea8af776b294f3b120e19e9b\", \"gpu_unittests\": \"fb74ac19d36b216332a6081ce8dff7f7e3a189e3\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"dea5f633aee9e4675152e12c791b63deafd56221\", \"headless_unittests\": \"2b5a715c920994f9b89443be22892a94a0409803\", \"install_static_unittests\": \"3775731a6ce2d0df4d6b3843ab332a1db5703820\", \"installer_util_unittests\": \"a4900d9bc0803e83748399688ba6d9b966772d1f\", \"interactive_ui_tests\": \"c1fb5f9a8b501e7b4dc90a289bb43d76340c93fc\", \"ipc_tests\": \"805c1f01de89b321aadc3a93c4ad0467241617a9\", \"jingle_unittests\": \"67713b28fe71aeafd7422fb714d976f3c67f5716\", \"latency_unittests\": \"288e9b59e1ca9c47190c0ad4fa9b41c067ba0b90\", \"libjingle_xmpp_unittests\": \"0b0302d46712da050f472870ac9aa96b45de8542\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"3752e1c4e6f371ed12bb0859427d01cb9a7261c3\", \"media_unittests\": \"dd5b39672dfe334b1fdc7f07d302ae1e8b4a74a4\", \"message_center_unittests\": \"6e5574bdfddb1a798821f0bce572043110b68460\", \"midi_unittests\": \"2bb9a430316fbd7e763af769d10052e39745c502\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"d031c7d47bacac7ca2329f6b4b4ac9fe029b8c73\", \"nacl_loader_unittests\": \"658603e3ced493df4d9f4036347ece72b3e6526a\", \"native_theme_unittests\": \"ede3b5feb2d66ee8e2cd332fd672c07dd4e273df\", \"net_unittests\": \"821e8ac99aec65694c866a8192ba24b283ba4881\", \"notification_helper_unittests\": \"8fe85098c9e895419632ff987e5cf210ac5cdfa9\", \"pdf_unittests\": \"f06dbec17c62d525f452c504e505ee9f6d10e3fb\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"ad18b5f5afdcd0d0c8584dfd3797921e628702ea\", \"printing_unittests\": \"026b7736f83a81e0371623c1ea190bbe41a8e662\", \"remoting_unittests\": \"11f34670d7d3d65f9e6ea5345af0417fc30f679c\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"02ba19f8cb6b0090bc0d2087061450a0168e3f8d\", \"services_unittests\": \"80f0ad8369ab4e949e7f0a03fa1caf889c6eaf4e\", \"setup_unittests\": \"1a00a9bbb2559185366db15405a59d99c6885a26\", \"shell_dialogs_unittests\": \"b3f279d791654decce66599fd8ac6308a8a8cddc\", \"skia_unittests\": \"5b792aaed3e32ae343ca3a52781eb59115fb5e02\", \"snapshot_unittests\": \"60fd7d08628a0f53e9b4a1a5f56b96c3fdad4b43\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"a7c1bfa87e4b516f0aee6b608ec8fe104c912b17\", \"sync_integration_tests\": \"3554a6396de8f0badf23a9ee0ab576ceb8587c95\", \"traffic_annotation_auditor_unittests\": \"8643cfe175dccb8f07a8df28cabe349fd994ea1d\", \"ui_base_unittests\": \"e33b7b33a8b96cf09b8d9c00f2f65112404dad1e\", \"ui_touch_selection_unittests\": \"3a8162deb71aa7d85e39b0d93f4fda29f4aee434\", \"unit_tests\": \"150bebad9586abc1ab4969220cd5a1ea94865209\", \"updater_tests\": \"b5f27ce043fa04953b8c1fa8eccfa34f79800ce7\", \"url_unittests\": \"ba0f3d8803d90bce09965c448068c8554996f7fc\", \"views_unittests\": \"08d38e61d92c8b1a8f0b0e835c64023a41503be7\", \"viz_unittests\": \"00a3f7865d8deafbf6b0f700dbac7a3c23e6ec88\", \"vr_common_unittests\": \"210da4cc93ca0283a72bc8e9193bd4340e22ad94\", \"vr_pixeltests\": \"a945ae29cef93552e53239aab095ccd13622c4d8\", \"weblayer_browsertests\": \"2b6a80c6d5f7a959d8b1c16da32cecbc02d198ac\", \"weblayer_unittests\": \"e0350cde0de0f2b0519bbf1e1aec01b35e2a2a87\", \"wm_unittests\": \"0207db89886ca2c47f3113f2f1d52a7029412eb5\", \"wtf_unittests\": \"fdb740139395f0050d09d643ea01dc97ec50e7f2\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-9-kl3f\"], \"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\": [\"5666-679c9bd\"], \"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": "1610936466015357",
"status": "COMPLETED",
"status_changed_ts": "1610939756659137",
"tags": [
"build_address:luci.chromium.ci/win-asan/16862",
"builder:win-asan",
"buildset:commit/git/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63de3bd4a15cc90d0b86d923626e6f133bca7e7c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023234467703578128",
"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/8857804405332314512/+/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:512c1d3405781010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610939756664121",
"url": "https://ci.chromium.org/b/8857804405332314512",
"utcnow_ts": "1610939757336184"
}
}
[03:15:57.391] Invocation finished in 54m57.944183616s with status SUCCEEDED