[08:51:40.677] New invocation is queued and will start shortly
[08:51:41.771] Starting the invocation (attempt 1)
[08:51:41.811] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:51:41.811] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:51:41.811] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023572275683076400",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b6f3b558e066f8ddb125983f636ee0342d09ce10\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023572275683076400",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"gitiles_ref:refs/heads/master"
]
}
[08:51:42.646] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610614301886294",
"id": "8858142213743857984",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b6f3b558e066f8ddb125983f636ee0342d09ce10\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610614302280466",
"tags": [
"build_address:luci.chromium.ci/win-asan/16812",
"builder:win-asan",
"buildset:commit/git/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023572275683076400",
"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/8858142213743857984/+/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": "1610614302280592",
"url": "https://ci.chromium.org/b/8858142213743857984",
"utcnow_ts": "1610614302638150"
}
}
[08:51:42.646] Task URL: https://ci.chromium.org/b/8858142213743857984
[08:51:42.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:2:0) after 1m0s
[08:51:47.705] Received PubSub notification, asking Buildbucket for the build status
[08:51:47.768] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:42.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:2:0)
[08:52:42.786] Timer tick, asking Buildbucket for the build status
[08:52:42.964] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:42.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:4:0) after 1m0s
[08:53:43.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:4:0)
[08:53:43.229] Timer tick, asking Buildbucket for the build status
[08:53:43.417] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:43.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:5:0) after 1m0s
[08:54:43.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:5:0)
[08:54:43.584] Timer tick, asking Buildbucket for the build status
[08:54:43.976] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:43.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:6:0) after 1m0s
[08:55:44.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:6:0)
[08:55:44.217] Timer tick, asking Buildbucket for the build status
[08:55:44.300] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:44.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:7:0) after 1m0s
[08:56:44.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:7:0)
[08:56:44.324] Timer tick, asking Buildbucket for the build status
[08:56:44.446] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:8:0) after 1m0s
[08:57:44.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:8:0)
[08:57:44.557] Timer tick, asking Buildbucket for the build status
[08:57:44.628] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:44.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:9:0) after 1m0s
[08:58:44.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:9:0)
[08:58:44.833] Timer tick, asking Buildbucket for the build status
[08:58:44.894] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:44.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:10:0) after 1m0s
[08:59:44.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:10:0)
[08:59:44.999] Timer tick, asking Buildbucket for the build status
[08:59:45.260] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:45.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:11:0) after 1m0s
[09:00:45.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:11:0)
[09:00:45.470] Timer tick, asking Buildbucket for the build status
[09:00:45.542] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:45.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:12:0) after 1m0s
[09:01:45.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:12:0)
[09:01:45.736] Timer tick, asking Buildbucket for the build status
[09:01:45.866] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:13:0) after 1m0s
[09:02:45.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:13:0)
[09:02:45.958] Timer tick, asking Buildbucket for the build status
[09:02:46.026] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:46.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:14:0) after 1m0s
[09:03:46.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:14:0)
[09:03:46.267] Timer tick, asking Buildbucket for the build status
[09:03:46.339] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:46.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:15:0) after 1m0s
[09:04:46.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:15:0)
[09:04:46.380] Timer tick, asking Buildbucket for the build status
[09:04:46.440] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:46.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:16:0) after 1m0s
[09:05:46.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:16:0)
[09:05:46.428] Timer tick, asking Buildbucket for the build status
[09:05:46.491] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:46.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:17:0) after 1m0s
[09:06:46.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:17:0)
[09:06:46.572] Timer tick, asking Buildbucket for the build status
[09:06:46.997] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:46.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:18:0) after 1m0s
[09:07:47.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:18:0)
[09:07:47.032] Timer tick, asking Buildbucket for the build status
[09:07:47.132] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:47.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:19:0) after 1m0s
[09:08:47.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:19:0)
[09:08:47.138] Timer tick, asking Buildbucket for the build status
[09:08:47.350] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:47.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:20:0) after 1m0s
[09:09:47.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:20:0)
[09:09:47.410] Timer tick, asking Buildbucket for the build status
[09:09:47.538] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:47.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:21:0) after 1m0s
[09:10:47.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:21:0)
[09:10:47.565] Timer tick, asking Buildbucket for the build status
[09:10:47.681] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:47.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:22:0) after 1m0s
[09:11:47.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:22:0)
[09:11:47.703] Timer tick, asking Buildbucket for the build status
[09:11:47.855] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:47.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:23:0) after 1m0s
[09:12:47.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:23:0)
[09:12:47.899] Timer tick, asking Buildbucket for the build status
[09:12:48.038] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:48.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:24:0) after 1m0s
[09:13:48.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:24:0)
[09:13:48.068] Timer tick, asking Buildbucket for the build status
[09:13:48.201] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:48.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:25:0) after 1m0s
[09:14:48.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:25:0)
[09:14:48.229] Timer tick, asking Buildbucket for the build status
[09:14:48.425] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:48.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:26:0) after 1m0s
[09:15:48.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:26:0)
[09:15:48.448] Timer tick, asking Buildbucket for the build status
[09:15:48.600] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:48.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:27:0) after 1m0s
[09:16:48.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:27:0)
[09:16:48.614] Timer tick, asking Buildbucket for the build status
[09:16:48.728] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:48.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:28:0) after 1m0s
[09:17:48.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:28:0)
[09:17:48.801] Timer tick, asking Buildbucket for the build status
[09:17:48.983] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:48.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:29:0) after 1m0s
[09:18:48.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:29:0)
[09:18:48.982] Timer tick, asking Buildbucket for the build status
[09:18:49.192] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:49.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:30:0) after 1m0s
[09:19:49.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:30:0)
[09:19:49.263] Timer tick, asking Buildbucket for the build status
[09:19:49.477] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:49.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:31:0) after 1m0s
[09:20:49.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:31:0)
[09:20:49.521] Timer tick, asking Buildbucket for the build status
[09:20:49.689] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:49.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:32:0) after 1m0s
[09:21:49.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:32:0)
[09:21:49.725] Timer tick, asking Buildbucket for the build status
[09:21:50.089] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:50.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:33:0) after 1m0s
[09:22:50.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:33:0)
[09:22:50.330] Timer tick, asking Buildbucket for the build status
[09:22:50.427] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:50.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:34:0) after 1m0s
[09:23:50.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:34:0)
[09:23:50.616] Timer tick, asking Buildbucket for the build status
[09:23:50.706] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:50.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:35:0) after 1m0s
[09:24:50.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:35:0)
[09:24:50.867] Timer tick, asking Buildbucket for the build status
[09:24:51.006] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:36:0) after 1m0s
[09:25:51.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:36:0)
[09:25:51.080] Timer tick, asking Buildbucket for the build status
[09:25:51.168] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:37:0) after 1m0s
[09:26:51.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:37:0)
[09:26:51.230] Timer tick, asking Buildbucket for the build status
[09:26:51.356] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:51.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:38:0) after 1m0s
[09:27:51.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:38:0)
[09:27:51.406] Timer tick, asking Buildbucket for the build status
[09:27:52.106] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:52.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:39:0) after 1m0s
[09:28:52.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:39:0)
[09:28:52.199] Timer tick, asking Buildbucket for the build status
[09:28:52.399] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:40:0) after 1m0s
[09:29:52.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:40:0)
[09:29:52.511] Timer tick, asking Buildbucket for the build status
[09:29:52.802] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:52.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:41:0) after 1m0s
[09:30:52.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:41:0)
[09:30:52.933] Timer tick, asking Buildbucket for the build status
[09:30:53.043] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:53.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:42:0) after 1m0s
[09:31:53.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:42:0)
[09:31:53.075] Timer tick, asking Buildbucket for the build status
[09:31:53.198] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:53.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:43:0) after 1m0s
[09:32:53.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:43:0)
[09:32:53.375] Timer tick, asking Buildbucket for the build status
[09:32:53.452] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:53.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:44:0) after 1m0s
[09:33:53.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:44:0)
[09:33:53.490] Timer tick, asking Buildbucket for the build status
[09:33:53.637] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:53.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:45:0) after 1m0s
[09:34:53.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:45:0)
[09:34:53.737] Timer tick, asking Buildbucket for the build status
[09:34:53.862] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:53.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:46:0) after 1m0s
[09:35:54.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:46:0)
[09:35:54.018] Timer tick, asking Buildbucket for the build status
[09:35:54.131] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:54.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:47:0) after 1m0s
[09:36:54.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:47:0)
[09:36:54.375] Timer tick, asking Buildbucket for the build status
[09:36:54.480] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:54.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:48:0) after 1m0s
[09:37:54.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:48:0)
[09:37:54.577] Timer tick, asking Buildbucket for the build status
[09:37:56.304] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:56.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:49:0) after 1m0s
[09:38:56.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:49:0)
[09:38:56.332] Timer tick, asking Buildbucket for the build status
[09:38:56.460] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:56.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:50:0) after 1m0s
[09:39:56.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:50:0)
[09:39:56.515] Timer tick, asking Buildbucket for the build status
[09:39:56.626] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:56.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:51:0) after 1m0s
[09:40:56.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:51:0)
[09:40:56.868] Timer tick, asking Buildbucket for the build status
[09:40:57.195] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:57.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:52:0) after 1m0s
[09:41:57.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:52:0)
[09:41:57.288] Timer tick, asking Buildbucket for the build status
[09:41:57.839] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:57.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:53:0) after 1m0s
[09:42:57.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:53:0)
[09:42:57.861] Timer tick, asking Buildbucket for the build status
[09:42:57.955] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:57.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:54:0) after 1m0s
[09:43:57.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:54:0)
[09:43:57.984] Timer tick, asking Buildbucket for the build status
[09:43:58.286] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:58.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:55:0) after 1m0s
[09:44:58.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:55:0)
[09:44:58.361] Timer tick, asking Buildbucket for the build status
[09:44:58.918] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:58.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:56:0) after 1m0s
[09:45:58.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:56:0)
[09:45:58.981] Timer tick, asking Buildbucket for the build status
[09:45:59.105] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:59.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:57:0) after 1m0s
[09:46:59.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:57:0)
[09:46:59.166] Timer tick, asking Buildbucket for the build status
[09:46:59.319] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:59.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:58:0) after 1m0s
[09:47:59.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:58:0)
[09:47:59.351] Timer tick, asking Buildbucket for the build status
[09:47:59.444] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:59.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:59:0) after 1m0s
[09:48:59.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:59:0)
[09:48:59.490] Timer tick, asking Buildbucket for the build status
[09:48:59.585] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:60:0) after 1m0s
[09:49:59.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:60:0)
[09:49:59.609] Timer tick, asking Buildbucket for the build status
[09:50:00.110] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:00.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:61:0) after 1m0s
[09:51:00.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:61:0)
[09:51:00.202] Timer tick, asking Buildbucket for the build status
[09:51:00.362] Build 8858142213743857984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:00.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023572275683076400:62:0) after 1m0s
[09:51:09.553] Received PubSub notification, asking Buildbucket for the build status
[09:51:09.716] Build 8858142213743857984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:51:09.716] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610617867949847",
"created_by": "project:chromium",
"created_ts": "1610614301886294",
"id": "8858142213743857984",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b6f3b558e066f8ddb125983f636ee0342d09ce10\"}}",
"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\": \"b6f3b558e066f8ddb125983f636ee0342d09ce10\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16812, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"198dc345383e1f17252945bdb4a6dd47f4345ad4\", \"got_dawn_revision\": \"6f833b7f78c92595d3ec74ee622dab1868fdf40b\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"b6f3b558e066f8ddb125983f636ee0342d09ce10\", \"got_revision_cp\": \"refs/heads/master@{#843441}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"712b239be61d29c242052c85f927da5291648e41\", \"got_v8_revision_cp\": \"refs/heads/8.9.272@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"b6f3b558e066f8ddb125983f636ee0342d09ce10\", \"swarm_hashes_refs/heads/master(at){#843441}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"bfa6f7be1b3309064d7bf3885e0e3f56afba9e53\", \"angle_unittests\": \"dd3bee314122ee6d324b179bfa33bf78e3b7f0b1\", \"app_shell_unittests\": \"868e82445e75ab07d8d2a6ee3a02b7e7e26fc457\", \"aura_unittests\": \"e4c0800b124dee7bb535eba02cf9e73bca49f9a7\", \"base_unittests\": \"8674b4152feee17838e350d0eecd0d6794db368f\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"67cd5762f7ab1c3b01e737dc4bceb6c8f93ddb38\", \"blink_fuzzer_unittests\": \"e8d81da8f93deef928580d0a06955c95a2ebcde7\", \"blink_heap_unittests\": \"27946a1471d65cd5bbe6d0ec206d3e9d8f7576d2\", \"blink_platform_unittests\": \"c976aab9bf3c59314fdd6bfc193444065f93907b\", \"blink_unittests\": \"86da19cf9ae0ddd02d043f342f8d022b5e99153a\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"3c46a0067b9379caeeaf50a0660b8cb7af81a535\", \"capture_unittests\": \"571c2500e8a1b83904287916e60bf9e7e1e038b3\", \"cast_unittests\": \"ff99bf6d77f69b79406185937f311b8d0ac37c01\", \"cc_unittests\": \"a4d8cae85e3f9bf1321f3bf83f174a3443a143aa\", \"chrome_app_unittests\": \"2f2109da89e2555f72d36994859f95f34e3caa78\", \"chrome_cleaner_unittests\": \"ebb65a3c205088022c8ea274168ca687dd68352a\", \"chrome_elf_unittests\": \"dc3e5bd0d4e871a50e174cedffd3cb45a888e3a9\", \"chromedriver_unittests\": \"dd1ed0baceba8eb397033a4ff60705812f2d0cc1\", \"components_browsertests\": \"d6b837b17e56c5d44319460eafa8a0f182bacf6b\", \"components_unittests\": \"ae403e704b3c160fdc4ded575adbba84f71fcb24\", \"compositor_unittests\": \"d7c61d0558c2d8ae3fe9af180df244e80a60afcf\", \"content_browsertests\": \"76f5cb7407440c35f0f3a5f2dab82e73827294d2\", \"content_unittests\": \"cb327a71fc71dd5b1476b1da5a92d2bead349b02\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"f7c7d2eb4905e1dcbc1b9fefef1e9377ca470421\", \"device_unittests\": \"75ea268cb02367b635d0ec9c8fcf70045ff5ae54\", \"display_unittests\": \"0b16569f1432aaa0fa4665ff271d1e39439dc44f\", \"elevation_service_unittests\": \"9ee59b4d9556e765a987af4297f6f74e268180b2\", \"events_unittests\": \"e853b144da4016d91518f2e9ff903aab37dceeb2\", \"extensions_browsertests\": \"e76630077480d3731c7aebbeaf322b1dbde5f468\", \"extensions_unittests\": \"8e407ffa3f06ece3ab56970566375865951561d6\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"542708573e98fe59544681bdabaeeae4b12fb47e\", \"gcp_unittests\": \"7b903b3f3b0e5529efe9459e6bd88344e918df36\", \"gfx_unittests\": \"3344ec1118da5b600ffa5995a201d85513ca719b\", \"gin_unittests\": \"9a39bf043ba3d175d34647ce69f9a77b4ff65647\", \"google_apis_unittests\": \"e7d02536f42b85b87b0c7a40be20abdb175c17ab\", \"gpu_unittests\": \"627ab251872b20c01718312106cc866ff30a4dac\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"ca30897bba77160f328be060cdd3ed01a8359982\", \"headless_unittests\": \"ff7ca107cd3bec69733b3b5f5bdb15dbd23cd637\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"d3e6eae4e8ef698c9d4f9d4cdcfb7d89b5efb91c\", \"interactive_ui_tests\": \"274f182605be9150f24987c5a03d9dbe9e49c8c1\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"5d15934670ec8d146d05a79f40944b56dba1cc06\", \"latency_unittests\": \"1b765adf3581700311a0913d4c0ebdd9489f1a9c\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"790df4642f9cf16df3507de2cd2ba94134635478\", \"media_unittests\": \"d65e4b7c76b734905d63d85eafdfd48ab25eee3b\", \"message_center_unittests\": \"ac0b699c61cc2780ec34f9c01b450c3e69091309\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"51952123d43ec6e6dc6947f52e9e33f37b981c1b\", \"nacl_loader_unittests\": \"37344939ffcedf3490eaf3e0e1c5cc9056fc07eb\", \"native_theme_unittests\": \"592a7736a8bd0345c62a28545506ddaac1ae1aa0\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"24665086e1820fc932612c091f2355389f22a1fd\", \"pdf_unittests\": \"6a0741f0599ec433b8f2f3ec19e6428e8667050c\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"a97a8c9fd5c7c1977afb6e1fcf94a959ded58de4\", \"printing_unittests\": \"00644c3c1d2733db1c19ae75fc6c5a4314d8cc31\", \"remoting_unittests\": \"b67dccdf58873a5640570b4b666a4e6d96c8e4b1\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"a9e7eddc801fe9c0b2981b51bc9ed45ddf879bad\", \"services_unittests\": \"d76a514366016442984ced79fc85397365f15d7e\", \"setup_unittests\": \"1f875fcbc3919b3df856cbc0c8f76a65302f4d20\", \"shell_dialogs_unittests\": \"99a16bebf4f6233986c80155a7fba6017a798d27\", \"skia_unittests\": \"9db21c2730a41032242ca7ac5468ab8993e5862e\", \"snapshot_unittests\": \"91351ab101160aebe4f21061d3506138b1a99fdd\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"347127cc90e346f3a1c4d003436240cc6aa8a3d3\", \"sync_integration_tests\": \"4d899644149478b5d021f4c34ad4eee86fc5e9c6\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"1119d01d4091b9a8b0aff0236e75262153d2baf6\", \"ui_touch_selection_unittests\": \"d31e5f1e5e37806d145d2f0c71fd6f6fb81c062d\", \"unit_tests\": \"54817a686c35c228aaf4a8942bff80e6e9d1db9d\", \"updater_tests\": \"c9bfee1f4ef7685e4d8ba3abbb41a675bcf485ff\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"9a37214c9c9f967fcff2946c2044302be349bc12\", \"viz_unittests\": \"dce9d236b30f9e4dc8b2123510478c0ec2d5604d\", \"vr_common_unittests\": \"bc0195fde53d4bccd687689fbc5a6e44caf40ef7\", \"vr_pixeltests\": \"ae725e547084260cc85fb45f98aa38935c8ea7cd\", \"weblayer_browsertests\": \"3f697ba3c4c3ac046535c310531cf9102fe290f0\", \"weblayer_unittests\": \"d254d5f6c25f10df547295a931e1af62786fb608\", \"wm_unittests\": \"a6b388aaacf594bdfc0c1b4818e559ea07f94c57\", \"wtf_unittests\": \"dd207320ae0a3d44b13678789f53aaf5ae5262ec\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610614304034026",
"status": "COMPLETED",
"status_changed_ts": "1610617868491393",
"tags": [
"build_address:luci.chromium.ci/win-asan/16812",
"builder:win-asan",
"buildset:commit/git/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6f3b558e066f8ddb125983f636ee0342d09ce10",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023572275683076400",
"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/8858142213743857984/+/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:5118e970c474a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1610617868515153",
"url": "https://ci.chromium.org/b/8858142213743857984",
"utcnow_ts": "1610617869705254"
}
}
[09:51:09.716] Invocation finished in 59m29.062259327s with status SUCCEEDED