[18:25:31.156] New invocation is queued and will start shortly
[18:25:32.367] Starting the invocation (attempt 1)
[18:25:32.399] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:25:32.399] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:25:32.400] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023173784834986304",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a1a653f3743b7ad6086eff7554fbc77a1deadc93\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023173784834986304",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"gitiles_ref:refs/heads/master"
]
}
[18:25:33.689] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610994332576537",
"id": "8857743722683480592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a1a653f3743b7ad6086eff7554fbc77a1deadc93\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610994333432522",
"tags": [
"build_address:luci.chromium.ci/win-asan/16876",
"builder:win-asan",
"buildset:commit/git/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023173784834986304",
"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/8857743722683480592/+/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": "1610994333432649",
"url": "https://ci.chromium.org/b/8857743722683480592",
"utcnow_ts": "1610994333670348"
}
}
[18:25:33.689] Task URL: https://ci.chromium.org/b/8857743722683480592
[18:25:33.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:2:0) after 1m0s
[18:25:44.865] Received PubSub notification, asking Buildbucket for the build status
[18:25:44.957] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:33.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:2:0)
[18:26:33.791] Timer tick, asking Buildbucket for the build status
[18:26:33.889] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:4:0) after 1m0s
[18:27:33.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:4:0)
[18:27:33.913] Timer tick, asking Buildbucket for the build status
[18:27:34.882] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:34.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:5:0) after 1m0s
[18:28:34.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:5:0)
[18:28:34.952] Timer tick, asking Buildbucket for the build status
[18:28:35.113] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:35.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:6:0) after 1m0s
[18:29:35.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:6:0)
[18:29:35.145] Timer tick, asking Buildbucket for the build status
[18:29:35.359] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:35.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:7:0) after 1m0s
[18:30:35.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:7:0)
[18:30:35.404] Timer tick, asking Buildbucket for the build status
[18:30:35.560] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:35.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:8:0) after 1m0s
[18:31:35.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:8:0)
[18:31:35.581] Timer tick, asking Buildbucket for the build status
[18:31:35.763] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:35.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:9:0) after 1m0s
[18:32:35.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:9:0)
[18:32:35.828] Timer tick, asking Buildbucket for the build status
[18:32:35.934] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:35.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:10:0) after 1m0s
[18:33:35.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:10:0)
[18:33:35.954] Timer tick, asking Buildbucket for the build status
[18:33:36.035] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:36.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:11:0) after 1m0s
[18:34:36.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:11:0)
[18:34:36.127] Timer tick, asking Buildbucket for the build status
[18:34:36.480] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:36.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:12:0) after 1m0s
[18:35:36.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:12:0)
[18:35:36.502] Timer tick, asking Buildbucket for the build status
[18:35:36.801] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:36.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:13:0) after 1m0s
[18:36:36.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:13:0)
[18:36:36.894] Timer tick, asking Buildbucket for the build status
[18:36:37.593] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:37.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:14:0) after 1m0s
[18:37:37.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:14:0)
[18:37:37.638] Timer tick, asking Buildbucket for the build status
[18:37:37.721] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:37.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:15:0) after 1m0s
[18:38:37.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:15:0)
[18:38:37.749] Timer tick, asking Buildbucket for the build status
[18:38:37.823] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:37.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:16:0) after 1m0s
[18:39:37.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:16:0)
[18:39:37.849] Timer tick, asking Buildbucket for the build status
[18:39:37.918] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:37.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:17:0) after 1m0s
[18:40:37.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:17:0)
[18:40:37.939] Timer tick, asking Buildbucket for the build status
[18:40:38.034] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:38.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:18:0) after 1m0s
[18:41:38.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:18:0)
[18:41:38.055] Timer tick, asking Buildbucket for the build status
[18:41:40.362] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:40.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:19:0) after 1m0s
[18:42:40.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:19:0)
[18:42:40.408] Timer tick, asking Buildbucket for the build status
[18:42:40.688] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:40.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:20:0) after 1m0s
[18:43:40.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:20:0)
[18:43:40.765] Timer tick, asking Buildbucket for the build status
[18:43:40.979] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:40.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:21:0) after 1m0s
[18:44:41.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:21:0)
[18:44:41.080] Timer tick, asking Buildbucket for the build status
[18:44:41.185] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:41.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:22:0) after 1m0s
[18:45:41.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:22:0)
[18:45:41.390] Timer tick, asking Buildbucket for the build status
[18:45:41.532] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:41.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:23:0) after 1m0s
[18:46:41.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:23:0)
[18:46:41.659] Timer tick, asking Buildbucket for the build status
[18:46:41.806] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:41.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:24:0) after 1m0s
[18:47:41.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:24:0)
[18:47:41.974] Timer tick, asking Buildbucket for the build status
[18:47:42.145] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:42.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:25:0) after 1m0s
[18:48:42.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:25:0)
[18:48:42.281] Timer tick, asking Buildbucket for the build status
[18:48:42.609] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:42.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:26:0) after 1m0s
[18:49:42.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:26:0)
[18:49:42.807] Timer tick, asking Buildbucket for the build status
[18:49:43.080] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:43.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:27:0) after 1m0s
[18:50:43.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:27:0)
[18:50:43.123] Timer tick, asking Buildbucket for the build status
[18:50:43.600] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:43.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:28:0) after 1m0s
[18:51:43.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:28:0)
[18:51:43.667] Timer tick, asking Buildbucket for the build status
[18:51:43.828] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:43.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:29:0) after 1m0s
[18:52:43.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:29:0)
[18:52:43.954] Timer tick, asking Buildbucket for the build status
[18:52:44.148] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:44.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:30:0) after 1m0s
[18:53:44.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:30:0)
[18:53:44.167] Timer tick, asking Buildbucket for the build status
[18:53:44.527] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:44.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:31:0) after 1m0s
[18:54:44.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:31:0)
[18:54:44.557] Timer tick, asking Buildbucket for the build status
[18:54:44.824] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:44.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:32:0) after 1m0s
[18:55:44.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:32:0)
[18:55:44.885] Timer tick, asking Buildbucket for the build status
[18:55:45.028] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:45.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:33:0) after 1m0s
[18:56:45.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:33:0)
[18:56:45.045] Timer tick, asking Buildbucket for the build status
[18:56:45.165] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:45.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:34:0) after 1m0s
[18:57:45.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:34:0)
[18:57:45.209] Timer tick, asking Buildbucket for the build status
[18:57:45.635] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:45.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:35:0) after 1m0s
[18:58:45.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:35:0)
[18:58:45.655] Timer tick, asking Buildbucket for the build status
[18:58:45.800] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:45.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:36:0) after 1m0s
[18:59:45.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:36:0)
[18:59:45.825] Timer tick, asking Buildbucket for the build status
[18:59:46.241] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:46.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:37:0) after 1m0s
[19:00:46.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:37:0)
[19:00:46.341] Timer tick, asking Buildbucket for the build status
[19:00:47.856] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:47.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:38:0) after 1m0s
[19:01:47.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:38:0)
[19:01:47.939] Timer tick, asking Buildbucket for the build status
[19:01:48.266] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:48.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:39:0) after 1m0s
[19:02:48.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:39:0)
[19:02:48.298] Timer tick, asking Buildbucket for the build status
[19:02:48.527] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:48.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:40:0) after 1m0s
[19:03:48.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:40:0)
[19:03:48.555] Timer tick, asking Buildbucket for the build status
[19:03:48.658] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:48.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:41:0) after 1m0s
[19:04:48.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:41:0)
[19:04:48.704] Timer tick, asking Buildbucket for the build status
[19:04:48.787] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:48.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:42:0) after 1m0s
[19:05:49.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:42:0)
[19:05:49.039] Timer tick, asking Buildbucket for the build status
[19:05:49.261] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:49.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:43:0) after 1m0s
[19:06:49.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:43:0)
[19:06:49.276] Timer tick, asking Buildbucket for the build status
[19:06:50.491] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:44:0) after 1m0s
[19:07:50.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:44:0)
[19:07:50.560] Timer tick, asking Buildbucket for the build status
[19:07:53.383] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:53.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:45:0) after 1m0s
[19:08:53.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:45:0)
[19:08:53.403] Timer tick, asking Buildbucket for the build status
[19:08:53.563] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:53.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:46:0) after 1m0s
[19:09:53.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:46:0)
[19:09:53.582] Timer tick, asking Buildbucket for the build status
[19:09:53.791] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:53.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:47:0) after 1m0s
[19:10:53.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:47:0)
[19:10:53.818] Timer tick, asking Buildbucket for the build status
[19:10:54.047] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:48:0) after 1m0s
[19:11:54.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:48:0)
[19:11:54.075] Timer tick, asking Buildbucket for the build status
[19:11:54.563] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:54.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:49:0) after 1m0s
[19:12:54.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:49:0)
[19:12:54.543] Timer tick, asking Buildbucket for the build status
[19:12:54.752] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:54.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:50:0) after 1m0s
[19:13:54.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:50:0)
[19:13:54.809] Timer tick, asking Buildbucket for the build status
[19:13:55.151] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:55.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:51:0) after 1m0s
[19:14:55.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:51:0)
[19:14:55.166] Timer tick, asking Buildbucket for the build status
[19:14:55.251] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:55.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:52:0) after 1m0s
[19:15:55.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:52:0)
[19:15:55.274] Timer tick, asking Buildbucket for the build status
[19:15:56.275] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:56.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:53:0) after 1m0s
[19:16:56.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:53:0)
[19:16:56.441] Timer tick, asking Buildbucket for the build status
[19:16:56.597] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:56.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:54:0) after 1m0s
[19:17:56.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:54:0)
[19:17:56.619] Timer tick, asking Buildbucket for the build status
[19:17:58.006] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:58.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:55:0) after 1m0s
[19:18:58.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:55:0)
[19:18:58.030] Timer tick, asking Buildbucket for the build status
[19:18:58.160] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:58.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:56:0) after 1m0s
[19:19:58.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:56:0)
[19:19:58.186] Timer tick, asking Buildbucket for the build status
[19:19:58.459] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:58.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:57:0) after 1m0s
[19:20:58.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:57:0)
[19:20:58.485] Timer tick, asking Buildbucket for the build status
[19:20:58.806] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:58.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:58:0) after 1m0s
[19:21:58.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:58:0)
[19:21:58.828] Timer tick, asking Buildbucket for the build status
[19:21:59.009] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:59.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:59:0) after 1m0s
[19:22:59.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:59:0)
[19:22:59.026] Timer tick, asking Buildbucket for the build status
[19:22:59.245] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:59.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:60:0) after 1m0s
[19:23:59.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:60:0)
[19:23:59.309] Timer tick, asking Buildbucket for the build status
[19:23:59.396] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:59.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:61:0) after 1m0s
[19:24:59.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:61:0)
[19:24:59.450] Timer tick, asking Buildbucket for the build status
[19:24:59.931] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:59.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:62:0) after 1m0s
[19:25:59.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:62:0)
[19:25:59.923] Timer tick, asking Buildbucket for the build status
[19:26:00.277] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:00.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:63:0) after 1m0s
[19:27:00.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:63:0)
[19:27:00.385] Timer tick, asking Buildbucket for the build status
[19:27:00.661] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:00.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:64:0) after 1m0s
[19:28:01.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:64:0)
[19:28:01.314] Timer tick, asking Buildbucket for the build status
[19:28:01.412] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:01.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:65:0) after 1m0s
[19:29:01.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:65:0)
[19:29:01.882] Timer tick, asking Buildbucket for the build status
[19:29:02.203] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:02.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:66:0) after 1m0s
[19:30:02.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:66:0)
[19:30:02.268] Timer tick, asking Buildbucket for the build status
[19:30:02.634] Build 8857743722683480592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:02.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023173784834986304:67:0) after 1m0s
[19:30:17.207] Received PubSub notification, asking Buildbucket for the build status
[19:30:17.351] Build 8857743722683480592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:30:17.351] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610998216114939",
"created_by": "project:chromium",
"created_ts": "1610994332576537",
"id": "8857743722683480592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a1a653f3743b7ad6086eff7554fbc77a1deadc93\"}}",
"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\": \"a1a653f3743b7ad6086eff7554fbc77a1deadc93\", \"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\": 16876, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"297e1ae196859bba28b0d82b152a315aee66d36d\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"a1a653f3743b7ad6086eff7554fbc77a1deadc93\", \"got_revision_cp\": \"refs/heads/master@{#844575}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"765e4f6f0ef4a47026202c7991312d412b99b524\", \"got_v8_revision\": \"e02dfe6cf35d7b3724c84b162c628b2c960bd9c7\", \"got_v8_revision_cp\": \"refs/heads/9.0.5@{#1}\", \"got_webrtc_revision\": \"12971a22d265c7ba1c3c388f5d487580939a1162\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33014}\", \"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\": \"a1a653f3743b7ad6086eff7554fbc77a1deadc93\", \"swarm_hashes_refs/heads/master(at){#844575}_without_patch\": {\"absl_hardening_tests\": \"b2bd6c342bca56214f0326f5b3d59c0751b1230f\", \"accessibility_unittests\": \"776a4bbb3c1f776fef31930c1b0f7ffb787ae299\", \"angle_unittests\": \"dd820334eb8020b645894902da14c186b3044a6f\", \"app_shell_unittests\": \"2aa65dc795b296178f51c452676b8ed1fd9ee5d6\", \"aura_unittests\": \"9d004191eb78b303d6bc434426daf46f83040693\", \"base_unittests\": \"2267d086af9eaa15f51aa812c53fa748fa8c7a08\", \"base_util_unittests\": \"fa7da58c0b3c623357afaaf808657bf369fa6bf4\", \"blink_common_unittests\": \"21e1b0f6f92b2d682377e9bd585571f7bd7152e2\", \"blink_fuzzer_unittests\": \"39d81d9916a7afe08bb93dc2ab66e3d893862f2d\", \"blink_heap_unittests\": \"eb2dad5edb4bbb65e8271cd8a0da2a0c0ec22cad\", \"blink_platform_unittests\": \"425aa6498d0dfc92b468868b6c9b95259f52df31\", \"blink_unittests\": \"41a81b8af5894f0cdcc8675f50d8fe8e248b9c1c\", \"boringssl_crypto_tests\": \"25552210dd63fca1c39520553252f3131cd083b1\", \"boringssl_ssl_tests\": \"b7c157ff01e7583885cb849c36f66181c5b50f78\", \"browser_switcher_bho_unittests\": \"824d32e969cea297d684eb91c38f1981d0ef79a6\", \"browser_tests\": \"9f49ea338f8354ba4fdc6d5cfc9395e4d46c5606\", \"capture_unittests\": \"c3e30fa5b3f0f2248dca8aa88330bf5ae5f4067e\", \"cast_unittests\": \"9365646f98f3fffd10846d533ba4deace0723990\", \"cc_unittests\": \"8963d70a11b178c64e2c4cf9620e8b037951a674\", \"chrome_app_unittests\": \"903bdd938162d026eeac829d0a59d01e699d4a2b\", \"chrome_cleaner_unittests\": \"9ff092ad9d08c9c010006080ff711b95d4e69ea9\", \"chrome_elf_unittests\": \"f7ddb74eabadcdb80e9e0d8312774787d22c857d\", \"chromedriver_unittests\": \"9812f80e1b98b5a55c45019b06e1da9eecfc9f9b\", \"components_browsertests\": \"040738da449b3f833e7d7479e972b2a885389a2b\", \"components_unittests\": \"bc374712af1748014eb5b0b4285fb1fb7be75ff7\", \"compositor_unittests\": \"dc4d19c6113c02ff574ff85062d8ffb9a0edd7f7\", \"content_browsertests\": \"ddcb7f76bcfa8ff8ab5e184f78e710fce2872b57\", \"content_unittests\": \"fd7ae610168367a58348934a37e73983f8f46554\", \"courgette_unittests\": \"252468d642eccf1659a534c16845a2a44b9e7acc\", \"crashpad_tests\": \"5bf305981a908aa1fd664ffde728754b53f9ff26\", \"cronet_tests\": \"156bb7d14ceb2706635ae912b989d960e307a11f\", \"cronet_unittests\": \"2f20c0319db483e47d6c4b164ab479459e7ae80b\", \"crypto_unittests\": \"d427ee9490c70742ed60a17dfe54a4cd6d4de2ca\", \"delayloads_unittests\": \"77a757859cb641163042019125940be11455032a\", \"device_unittests\": \"2644e6e133645e65f31732d108c86dcb56a85ea3\", \"display_unittests\": \"40fdb957f3f36c6dabd8f0e86e5a63fde762e5c3\", \"elevation_service_unittests\": \"6aec424d14960bb50fc6b79fbfd44a2b6f956050\", \"events_unittests\": \"75213328811659be0b1985909b967b5a1e415804\", \"extensions_browsertests\": \"18340512e64ad57deda3529a22148418f207e44c\", \"extensions_unittests\": \"f351885d73ba2e9879454f7b10deb8a3a6226acc\", \"filesystem_service_unittests\": \"b82db50dec44f57ce4a343c321b0fd47e1141b69\", \"gcm_unit_tests\": \"e721f484fc01d23f1ce937637091453c60d72e7f\", \"gcp_unittests\": \"af3e8f36784d6e9768d93e36c30d1d62cc9a805a\", \"gfx_unittests\": \"d138411bb0b3b710168f5fc5a071265a704f4d08\", \"gin_unittests\": \"1c0430794ce4f38a0640dbced5104868d83f051d\", \"google_apis_unittests\": \"f189f54d032d98dfc8c6b8b747e55e70d4480a12\", \"gpu_unittests\": \"bad64cbfd47f8df0176c7cfa28c76c912e31a2ee\", \"gwp_asan_unittests\": \"3ac71994306fdcb4b00649fff300287475a0d197\", \"headless_browsertests\": \"ca75828639181a5a779c69148a0d2cc483b1eeaa\", \"headless_unittests\": \"a5eead502f3eeca1ba0f3252260affd17f9854cf\", \"install_static_unittests\": \"d4a3944e7b44fbdddaefb10c69d7c90589e275c4\", \"installer_util_unittests\": \"866c4cb756ed714d50fc31178a3bf2d846302eb5\", \"interactive_ui_tests\": \"de97c71842834ef6bc65976e9f142f0bea724840\", \"ipc_tests\": \"42094e68ea655daa13b21776e2f3fcdeb4f4f704\", \"jingle_unittests\": \"3057d1e2eab46efd0eb88586a31ae9d170697926\", \"latency_unittests\": \"10c1867253e93f91ce7745c55dce76d5d8f9ac1b\", \"libjingle_xmpp_unittests\": \"e68818a0491aaaa7b46f8d7cfa6c5d9fb14dffc3\", \"liburlpattern_unittests\": \"98734a0021b09f8e13d427f64c23a4c31ceebfa0\", \"media_blink_unittests\": \"464224caa2ac925b305f934eb29e8215fa618402\", \"media_unittests\": \"6214fd999df318b683de2e798658dc79aedb8b7c\", \"message_center_unittests\": \"1e3ebde7145c33c2ca7f55f667accb7d77e122e0\", \"midi_unittests\": \"d84a591284d36d9248dd889581a996b522169a6b\", \"mojo_core_unittests\": \"516bc3fe88a70c529250d484ed5a57414d8bfce7\", \"mojo_unittests\": \"4e78da33fa3f8c5b6bfbcb442a9130aed727cf06\", \"nacl_loader_unittests\": \"b9e29b37f0c9a07413a696e15cda714101631b3b\", \"native_theme_unittests\": \"b497426238b22537d03a3d387223991eeac85cdc\", \"net_unittests\": \"6d1033db3423b70472766946fc23bb81cad8cd4b\", \"notification_helper_unittests\": \"86d24267cb72bfe2d4200904800ca69b947590c3\", \"pdf_unittests\": \"1728ba067d34b2137339d26a49d5ad94969ae49e\", \"perfetto_unittests\": \"2bb57c4988d49ded34d092f7a5654759f48a795f\", \"ppapi_unittests\": \"5eece32f0b07e53e95d5a9178c24ba2d34705bcd\", \"printing_unittests\": \"2a00c5beda221fdff30e4cc5ef1d709b8ba704da\", \"remoting_unittests\": \"4a576fff502b2e194eb56f90faa482dfcaf411aa\", \"sbox_integration_tests\": \"1d469d045d60e1ffa5eebf1a84d3484bf2371080\", \"sbox_unittests\": \"129e8fb43ef315c61f61c0b92cbba1d495900180\", \"sbox_validation_tests\": \"2268053318ee6a817b17db7a33340629f7241862\", \"service_manager_unittests\": \"28ee24e4c446f57e68be353efe062db4ab076a8c\", \"services_unittests\": \"b3d75c1d5f8029caf3cc99e4c88aef7aa7541cab\", \"setup_unittests\": \"6fc6cf5677a2d512a536f7300ea1637386785304\", \"shell_dialogs_unittests\": \"eeebfab30d3c3362aebd458720e4d2fed288d37a\", \"skia_unittests\": \"985b6b248b1486e75654bfb736b6b76c85d0fa8b\", \"snapshot_unittests\": \"3783c2d1025b4810656a011555ebbe7df19d6738\", \"sql_unittests\": \"35a92e9304ea5dfc7da8f736e8b322830c90a6bd\", \"storage_unittests\": \"aa37d753ce5a5c63c1e8bd12625bcd9801681db4\", \"sync_integration_tests\": \"372f6a8c918cc035b29cb35500c50c06f01725ad\", \"traffic_annotation_auditor_unittests\": \"56f85fff2747ac9847d70bf2f09d35d95d70c717\", \"ui_base_unittests\": \"6e08776238c4ff2cde9687e3e2870a251da002e4\", \"ui_touch_selection_unittests\": \"09c7a781a419826c60df90b9d53b404f249374a2\", \"unit_tests\": \"905b44bb99c3109b85617b3d0643356ff7b3123b\", \"updater_tests\": \"29f7e02617fb59fbc81b9f1725e4f7586e9ff495\", \"url_unittests\": \"6bc1437e481a5b549ef76301f9204f3d104bb177\", \"views_unittests\": \"30bec30f0f0b7cee3fd667f2ca1dd6a32ad1b304\", \"viz_unittests\": \"54b92083213c1ca87d79cb7cb62844c35cc807fe\", \"vr_common_unittests\": \"e4d15b1b6b9db73ef97b6118a5604128b3becea6\", \"vr_pixeltests\": \"0435a2bff8df1cc19702e30f2571caa75eb379c4\", \"weblayer_browsertests\": \"3919bb631e5bbc1f24ac301677a26eae10263e18\", \"weblayer_unittests\": \"5063ebc9debe7a4dfb70aaaf9a0ff8386b9ed88e\", \"wm_unittests\": \"e22b7e093ae347754a9ad28fbeb619d41e75dfc7\", \"wtf_unittests\": \"6528d301f73deaea3b5ccc3116a79d07fdba49e8\", \"zlib_unittests\": \"35170c277cc902a46a93a19d7a37646ec8afece0\", \"zucchini_unittests\": \"ff67a57ae7f3a5c1841dd163ac259b8cda2f5623\"}}, \"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": "1610994342760274",
"status": "COMPLETED",
"status_changed_ts": "1610998216562357",
"tags": [
"build_address:luci.chromium.ci/win-asan/16876",
"builder:win-asan",
"buildset:commit/git/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a1a653f3743b7ad6086eff7554fbc77a1deadc93",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023173784834986304",
"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/8857743722683480592/+/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:512f90480a0eae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610998216566895",
"url": "https://ci.chromium.org/b/8857743722683480592",
"utcnow_ts": "1610998217337561"
}
}
[19:30:17.351] Invocation finished in 1h4m46.211181219s with status SUCCEEDED