[17:01:06.216] New invocation is queued and will start shortly
[17:01:07.341] Starting the invocation (attempt 1)
[17:01:07.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:01:07.418] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:01:07.418] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019374023221971504",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019374023221971504",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"gitiles_ref:refs/heads/master"
]
}
[17:01:09.157] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614618067619273",
"id": "8853943961086797088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614618068710703",
"tags": [
"build_address:luci.chromium.ci/win-asan/17577",
"builder:win-asan",
"buildset:commit/git/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019374023221971504",
"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/8853943961086797088/+/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": "1614618068710817",
"url": "https://ci.chromium.org/b/8853943961086797088",
"utcnow_ts": "1614618069033202"
}
}
[17:01:09.157] Task URL: https://ci.chromium.org/b/8853943961086797088
[17:01:09.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:2:0) after 1m0s
[17:01:16.031] Received PubSub notification, asking Buildbucket for the build status
[17:01:16.129] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:09.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:2:0)
[17:02:09.159] Timer tick, asking Buildbucket for the build status
[17:02:09.230] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:09.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:4:0) after 1m0s
[17:03:09.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:4:0)
[17:03:09.248] Timer tick, asking Buildbucket for the build status
[17:03:09.371] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:09.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:5:0) after 1m0s
[17:04:09.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:5:0)
[17:04:09.438] Timer tick, asking Buildbucket for the build status
[17:04:09.513] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:09.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:6:0) after 1m0s
[17:05:09.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:6:0)
[17:05:09.491] Timer tick, asking Buildbucket for the build status
[17:05:09.556] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:09.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:7:0) after 1m0s
[17:06:09.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:7:0)
[17:06:09.598] Timer tick, asking Buildbucket for the build status
[17:06:09.746] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:09.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:8:0) after 1m0s
[17:07:09.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:8:0)
[17:07:09.810] Timer tick, asking Buildbucket for the build status
[17:07:09.937] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:09.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:9:0) after 1m0s
[17:08:10.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:9:0)
[17:08:10.008] Timer tick, asking Buildbucket for the build status
[17:08:10.109] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:10.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:10:0) after 1m0s
[17:09:10.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:10:0)
[17:09:10.122] Timer tick, asking Buildbucket for the build status
[17:09:10.192] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:11:0) after 1m0s
[17:10:10.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:11:0)
[17:10:10.284] Timer tick, asking Buildbucket for the build status
[17:10:10.417] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:10.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:12:0) after 1m0s
[17:11:10.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:12:0)
[17:11:10.436] Timer tick, asking Buildbucket for the build status
[17:11:11.676] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:11.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:13:0) after 1m0s
[17:12:11.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:13:0)
[17:12:11.729] Timer tick, asking Buildbucket for the build status
[17:12:14.441] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:14.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:14:0) after 1m0s
[17:13:14.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:14:0)
[17:13:14.481] Timer tick, asking Buildbucket for the build status
[17:13:15.144] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:15.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:15:0) after 1m0s
[17:14:15.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:15:0)
[17:14:15.166] Timer tick, asking Buildbucket for the build status
[17:14:15.271] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:15.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:16:0) after 1m0s
[17:15:15.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:16:0)
[17:15:15.289] Timer tick, asking Buildbucket for the build status
[17:15:15.408] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:15.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:17:0) after 1m0s
[17:16:15.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:17:0)
[17:16:15.443] Timer tick, asking Buildbucket for the build status
[17:16:15.517] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:15.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:18:0) after 1m0s
[17:17:15.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:18:0)
[17:17:15.580] Timer tick, asking Buildbucket for the build status
[17:17:15.730] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:15.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:19:0) after 1m0s
[17:18:15.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:19:0)
[17:18:15.770] Timer tick, asking Buildbucket for the build status
[17:18:15.839] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:15.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:20:0) after 1m0s
[17:19:15.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:20:0)
[17:19:15.847] Timer tick, asking Buildbucket for the build status
[17:19:16.115] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:16.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:21:0) after 1m0s
[17:20:16.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:21:0)
[17:20:16.148] Timer tick, asking Buildbucket for the build status
[17:20:16.276] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:16.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:22:0) after 1m0s
[17:21:16.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:22:0)
[17:21:16.385] Timer tick, asking Buildbucket for the build status
[17:21:16.515] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:16.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:23:0) after 1m0s
[17:22:16.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:23:0)
[17:22:16.557] Timer tick, asking Buildbucket for the build status
[17:22:16.701] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:16.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:24:0) after 1m0s
[17:23:16.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:24:0)
[17:23:16.734] Timer tick, asking Buildbucket for the build status
[17:23:16.867] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:16.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:25:0) after 1m0s
[17:24:16.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:25:0)
[17:24:16.932] Timer tick, asking Buildbucket for the build status
[17:24:17.332] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:17.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:26:0) after 1m0s
[17:25:17.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:26:0)
[17:25:17.416] Timer tick, asking Buildbucket for the build status
[17:25:17.487] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:17.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:27:0) after 1m0s
[17:26:17.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:27:0)
[17:26:17.609] Timer tick, asking Buildbucket for the build status
[17:26:17.722] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:17.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:28:0) after 1m0s
[17:27:17.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:28:0)
[17:27:17.848] Timer tick, asking Buildbucket for the build status
[17:27:20.671] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:20.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:29:0) after 1m0s
[17:28:20.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:29:0)
[17:28:20.724] Timer tick, asking Buildbucket for the build status
[17:28:21.277] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:21.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:30:0) after 1m0s
[17:29:21.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:30:0)
[17:29:21.369] Timer tick, asking Buildbucket for the build status
[17:29:21.777] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:21.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:31:0) after 1m0s
[17:30:21.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:31:0)
[17:30:21.801] Timer tick, asking Buildbucket for the build status
[17:30:21.883] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:21.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:32:0) after 1m0s
[17:31:21.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:32:0)
[17:31:21.932] Timer tick, asking Buildbucket for the build status
[17:31:22.656] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:22.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:33:0) after 1m0s
[17:32:22.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:33:0)
[17:32:22.932] Timer tick, asking Buildbucket for the build status
[17:32:23.111] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:23.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:34:0) after 1m0s
[17:33:23.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:34:0)
[17:33:23.243] Timer tick, asking Buildbucket for the build status
[17:33:23.469] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:23.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:35:0) after 1m0s
[17:34:23.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:35:0)
[17:34:23.476] Timer tick, asking Buildbucket for the build status
[17:34:23.607] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:23.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:36:0) after 1m0s
[17:35:23.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:36:0)
[17:35:23.873] Timer tick, asking Buildbucket for the build status
[17:35:23.956] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:23.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:37:0) after 1m0s
[17:36:23.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:37:0)
[17:36:23.964] Timer tick, asking Buildbucket for the build status
[17:36:24.107] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:24.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:38:0) after 1m0s
[17:37:24.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:38:0)
[17:37:24.124] Timer tick, asking Buildbucket for the build status
[17:37:24.247] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:24.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:39:0) after 1m0s
[17:38:25.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:39:0)
[17:38:25.087] Timer tick, asking Buildbucket for the build status
[17:38:25.269] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:25.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:40:0) after 1m0s
[17:39:25.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:40:0)
[17:39:25.301] Timer tick, asking Buildbucket for the build status
[17:39:26.389] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:26.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:41:0) after 1m0s
[17:40:26.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:41:0)
[17:40:26.533] Timer tick, asking Buildbucket for the build status
[17:40:26.653] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:26.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:42:0) after 1m0s
[17:41:26.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:42:0)
[17:41:26.639] Timer tick, asking Buildbucket for the build status
[17:41:26.845] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:26.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:43:0) after 1m0s
[17:42:26.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:43:0)
[17:42:26.888] Timer tick, asking Buildbucket for the build status
[17:42:26.986] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:26.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:44:0) after 1m0s
[17:43:27.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:44:0)
[17:43:27.007] Timer tick, asking Buildbucket for the build status
[17:43:27.336] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:27.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:45:0) after 1m0s
[17:44:27.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:45:0)
[17:44:27.362] Timer tick, asking Buildbucket for the build status
[17:44:27.448] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:27.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:46:0) after 1m0s
[17:45:27.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:46:0)
[17:45:27.471] Timer tick, asking Buildbucket for the build status
[17:45:27.560] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:27.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:47:0) after 1m0s
[17:46:27.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:47:0)
[17:46:27.673] Timer tick, asking Buildbucket for the build status
[17:46:27.761] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:27.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:48:0) after 1m0s
[17:47:27.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:48:0)
[17:47:27.889] Timer tick, asking Buildbucket for the build status
[17:47:27.964] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:49:0) after 1m0s
[17:48:28.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:49:0)
[17:48:28.116] Timer tick, asking Buildbucket for the build status
[17:48:28.217] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:28.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:50:0) after 1m0s
[17:49:28.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:50:0)
[17:49:28.755] Timer tick, asking Buildbucket for the build status
[17:49:30.894] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:30.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:51:0) after 1m0s
[17:50:32.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:51:0)
[17:50:32.062] Timer tick, asking Buildbucket for the build status
[17:50:33.832] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:33.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:52:0) after 1m0s
[17:51:33.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:52:0)
[17:51:33.879] Timer tick, asking Buildbucket for the build status
[17:51:34.146] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:34.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:53:0) after 1m0s
[17:52:34.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:53:0)
[17:52:34.195] Timer tick, asking Buildbucket for the build status
[17:52:34.336] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:34.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:54:0) after 1m0s
[17:53:34.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:54:0)
[17:53:34.370] Timer tick, asking Buildbucket for the build status
[17:53:34.757] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:55:0) after 1m0s
[17:54:34.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:55:0)
[17:54:34.839] Timer tick, asking Buildbucket for the build status
[17:54:34.943] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:34.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:56:0) after 1m0s
[17:55:34.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:56:0)
[17:55:34.955] Timer tick, asking Buildbucket for the build status
[17:55:35.028] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:35.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:57:0) after 1m0s
[17:56:35.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:57:0)
[17:56:35.129] Timer tick, asking Buildbucket for the build status
[17:56:35.299] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:58:0) after 1m0s
[17:57:35.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:58:0)
[17:57:35.572] Timer tick, asking Buildbucket for the build status
[17:57:36.169] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:36.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:59:0) after 1m0s
[17:58:36.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:59:0)
[17:58:36.199] Timer tick, asking Buildbucket for the build status
[17:58:36.410] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:36.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:60:0) after 1m0s
[17:59:36.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:60:0)
[17:59:36.382] Timer tick, asking Buildbucket for the build status
[17:59:36.490] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:36.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:61:0) after 1m0s
[18:00:36.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:61:0)
[18:00:36.558] Timer tick, asking Buildbucket for the build status
[18:00:36.687] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:36.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:62:0) after 1m0s
[18:01:36.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:62:0)
[18:01:36.750] Timer tick, asking Buildbucket for the build status
[18:01:36.848] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:63:0) after 1m0s
[18:02:36.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:63:0)
[18:02:36.869] Timer tick, asking Buildbucket for the build status
[18:02:38.924] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:38.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:64:0) after 1m0s
[18:03:39.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:64:0)
[18:03:39.067] Timer tick, asking Buildbucket for the build status
[18:03:39.167] Build 8853943961086797088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:39.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019374023221971504:65:0) after 1m0s
[18:04:09.241] Received PubSub notification, asking Buildbucket for the build status
[18:04:09.359] Build 8853943961086797088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:04:09.359] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614621848109454",
"created_by": "project:chromium",
"created_ts": "1614618067619273",
"id": "8853943961086797088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\"}}",
"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\": \"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\", \"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-2-2mmz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17577, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"0e11e49d1937d44ffb6345b115d13cf8cee24695\", \"got_dawn_revision\": \"19f111f69c550f5cddec76557a0b7808adae9c66\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\", \"got_revision_cp\": \"refs/heads/master@{#858577}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"8898c11ab30f9c0cc7453c87af8cf8f7f623c94c\", \"swarm_hashes_refs/heads/master(at){#858577}_without_patch\": {\"absl_hardening_tests\": \"3748e530a9a28fbdafeb1765c4ed7cdbaef733fa\", \"accessibility_unittests\": \"c9fdd4315b21fa1e64643bd276458f5974548294\", \"angle_unittests\": \"7125d320ef4b3e0604245844edd41cdbf7018cd1\", \"app_shell_unittests\": \"cc5312683611f5f42b46b62cbc103e8af469c5b0\", \"aura_unittests\": \"03992f7ca60573a6535ef9612d3995cc90a34a78\", \"base_unittests\": \"4df8f545ae061b3927964a0549b1a02fdc067b00\", \"base_util_unittests\": \"1dae3bb5255f3030cbd7ae2f0a8b7513a1ad2743\", \"blink_common_unittests\": \"ee184caf43c19039874abb47878423077364f298\", \"blink_fuzzer_unittests\": \"158ffc29ba5ea9279f2cbf91184e1608df76c0cf\", \"blink_heap_unittests\": \"6e69778e4cfd50dc00497d2d735e441dcb3b8eae\", \"blink_platform_unittests\": \"e830e48e17282464b7b8be09c65822c98fee4193\", \"blink_unittests\": \"cfcd5e84741bc5c278fabb565ec578b4e3ad367e\", \"boringssl_crypto_tests\": \"2f9e00fafbf6eb707d6aed78d71c2c60c279913a\", \"boringssl_ssl_tests\": \"bf4dcb825400801c786be47307f8d1b17d972b25\", \"browser_switcher_bho_unittests\": \"7d3ddd29f2daa6808797021a5895b0b5df252ba6\", \"browser_tests\": \"22cfac3c843d6a70abf2d73bcb112a0a8dae0873\", \"capture_unittests\": \"e73824032bd4ba0b13628356cc88a2e52e5cc77c\", \"cast_unittests\": \"135168e1a6468eb26a17f829ade19023e4b17f43\", \"cc_unittests\": \"614e3a104b87ce08120c92118b5b34744dfae6d4\", \"chrome_app_unittests\": \"b149745b959098df5f95ee4d4d370bf9cb23b7d9\", \"chrome_cleaner_unittests\": \"614272e1185f41752ea3b6ae92eee35e65618764\", \"chrome_elf_unittests\": \"d270b75bf71d25e7b7881a275c8ca66f4268633d\", \"chromedriver_unittests\": \"aa028effeca0d987ee855d0a0a45e4a1405a7fc4\", \"color_unittests\": \"f34ca76ade99eb55076630a71486fa13b4a57385\", \"components_browsertests\": \"9b5d99c6bc8e89725e798b540e7bb8649efecf8d\", \"components_unittests\": \"e0d1e3c4da9e032fdbb590bdba6fc134657fecbc\", \"compositor_unittests\": \"18041f5bb580046186cf2c6a230045058a8a2224\", \"content_browsertests\": \"90524c53dcabd470876e51d6d38b93705b1248da\", \"content_unittests\": \"5f1a256e6d781b39c1de6c5077d6d06b5ce3f37f\", \"courgette_unittests\": \"144a1ebfabb870a27b48883f0c05d0d09ba38eff\", \"crashpad_tests\": \"b6026cd1685502f170b5515c69bc415410a8c5bd\", \"cronet_tests\": \"314dbf60e7136c3d7db85dc94113c80fed4614d9\", \"cronet_unittests\": \"c6c00d2855fc54994d988e0e3d59af923ef06785\", \"crypto_unittests\": \"afc98410281c94dd8c5b3f6da6785732a2ebdcc9\", \"delayloads_unittests\": \"eff2fbfebbd36687af2f1fe480e0b4121cef93ed\", \"device_unittests\": \"27fb22f69c406ab60ecda663e20ebc705be41837\", \"display_unittests\": \"4b057fecbc790411af3ba3049617eb71ada594e4\", \"elevation_service_unittests\": \"9a5f94109875b586608be77255734d94a0941a3a\", \"events_unittests\": \"16ea10a952a5334487132c0b2b6adc13fedd624d\", \"extensions_browsertests\": \"523106ea69949bc4c7d19e1aa5d9daf60b354eb0\", \"extensions_unittests\": \"32bd61e558abefbc7267841740824ba9c090757a\", \"filesystem_service_unittests\": \"c673056f033183b70cce10fa74e10d889d49d2e0\", \"gcm_unit_tests\": \"97077bbcdce440ee0a3ad74dea3505bfc787fdbf\", \"gcp_unittests\": \"ffe4ac9dcee2e0144174e4b4c41e65f34f43d834\", \"gfx_unittests\": \"115fea6585a45196b8cf3174b7cd031ba747be79\", \"gin_unittests\": \"18dc660298f47c99c5daee13be2344968b6c35bb\", \"google_apis_unittests\": \"1d08a4920e9825405d18753ef658a39d6b54c380\", \"gpu_unittests\": \"0a1ae91ff1f7e3dc42b562f613dde9152dec53c7\", \"gwp_asan_unittests\": \"1ff962d30861576a942c17a99240a29bd14f15b4\", \"headless_browsertests\": \"bcfd0e851a8f0d5cca215e7b73fdfe2ce85d72f1\", \"headless_unittests\": \"8c3ee4d336101de42bf3c862e77bcfbfe3321dc0\", \"install_static_unittests\": \"5c83a1aefcfeb15a7fadab80a7fa3ab3254900c6\", \"installer_util_unittests\": \"067a06afcc4a97e72834476ecba64962a5ab42a7\", \"interactive_ui_tests\": \"29149a679b54aaa605ab11a79796a63e1a6dcf54\", \"ipc_tests\": \"a09ee0821a0a2424432db195a5ca477b8e85a977\", \"jingle_unittests\": \"d60230e6c1b22fd983bf3309c874190c32e70778\", \"latency_unittests\": \"59fcb8248de342dc118b7900540d1f3d5c9580d9\", \"libjingle_xmpp_unittests\": \"6750ee7a7117c2246b52b34841e12857d693b3f4\", \"liburlpattern_unittests\": \"4942b1ac8dae595d8ee2330ab26cdfb453ca547c\", \"media_blink_unittests\": \"605b92447b223ab6ab5a7560bc9cbdcc6b8f6170\", \"media_unittests\": \"eb33bf51175f32e7af0ce59f880fbf8d48b6f1d4\", \"message_center_unittests\": \"779d79d5bffc35ce69dd7bc9db37dacaf7204b7a\", \"midi_unittests\": \"64501ad57a1888e6c4a2c4eefcdae8eb7e863efa\", \"mojo_core_unittests\": \"9f293de5b8f48ad63d798001b6c750ffe43fec92\", \"mojo_unittests\": \"ca26e14d5312ed31770af61318a4232a68ed9d51\", \"nacl_loader_unittests\": \"bc55bd94a52518924fe94b5ce58bb73df59ccdf8\", \"native_theme_unittests\": \"1bc2ebeeed507f61551cb3b0750aa1e0c7bcc058\", \"net_unittests\": \"9c59da53937ec0efa72e85b1e7fc17494955d4d4\", \"notification_helper_unittests\": \"0aacfa2bd44b8e46beb44cd8583009d9953509f5\", \"pdf_unittests\": \"b659c4ee909d5e026c2d08ddca12182d192f8046\", \"perfetto_unittests\": \"bd80926c78b0f787e8096b6d2e02d2e26e90b7c3\", \"ppapi_unittests\": \"586312d16517db275cbbffaf0dadf7e36ed50bfe\", \"printing_unittests\": \"d85883f1c589fc31627257828b8ed7ba45862b4d\", \"remoting_unittests\": \"7dee7337ddf9359ee29d6bcc6a801a927066f1ba\", \"sbox_integration_tests\": \"f3090b94f4bc422bb1ef019a320a23e2f8bf6b2b\", \"sbox_unittests\": \"cd311bbeb8a98a6f62afc1d3f44608533359d023\", \"sbox_validation_tests\": \"0379f62ac5070f612301bc09d1dfe708684fddde\", \"service_manager_unittests\": \"179d156836b86d20dffaa3dda0fd70d53f4e5542\", \"services_unittests\": \"f2d767fd18cd157051dade9faa5109bfa7235f6f\", \"setup_unittests\": \"afb84cfdb092cb44076f169a7d9ff5097886685e\", \"shell_dialogs_unittests\": \"4cdd174cfa2253342883a84d764b8891ff4ead64\", \"skia_unittests\": \"aafbe034f9da35ca0df638f39f01a9a23eb4a5cc\", \"snapshot_unittests\": \"180a0f54b9cfe6ec18327f61b8b13ffd25d8fd5d\", \"sql_unittests\": \"36291e904617bbd031853c87e008d566645948bb\", \"storage_unittests\": \"0ad614547f7bbc3cfa107c4c480d4f5d7f4294b5\", \"sync_integration_tests\": \"ae2fbbf2e817af81e7f640917bcda22ece90f4f9\", \"traffic_annotation_auditor_unittests\": \"18f5faccdf4dda401da2cb0554c7c7f29e6b094b\", \"ui_base_unittests\": \"70a16727ec8242e817243dcfd55890cfac27cd92\", \"ui_touch_selection_unittests\": \"097567bbed27878255f4b24f7dfdc5530066e1c7\", \"unit_tests\": \"ae0bc3e49346fe397ccd81862aad6609ccbced96\", \"updater_tests\": \"19292b53469390dcac01b65d27343db9c42ac9e5\", \"url_unittests\": \"d26435cacdc06452174d6e02c2a176aae2e2d5f6\", \"views_unittests\": \"2127740aff8038cb723bee6361206368beb39068\", \"viz_unittests\": \"be7c0764489a102e67476faf7576269f8a416351\", \"vr_common_unittests\": \"1f0d620c43ee6f8fb68e1ccda8ace1077a641b14\", \"vr_pixeltests\": \"c2246291eb4d162bde7653e8da6119d273ea6d74\", \"weblayer_browsertests\": \"bb8be9e5fede45c8314bbf85e6cfd69b8202c420\", \"weblayer_unittests\": \"c44ffbcff6016c959b222fde66e828384d38d5ad\", \"wm_unittests\": \"8340240265c7da7439df72f0fd9cd3f90b080739\", \"wtf_unittests\": \"632769c27f6038d6729e431462cc5b37b0e59dcf\", \"zlib_unittests\": \"92ca985bef4487ca40a3a8c7be4b1b445d425453\", \"zucchini_unittests\": \"3b5711a1d2f10876590b556555e8b376ed3b8d99\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-2-2mmz\"], \"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\": [\"5712-339372b\"], \"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": "1614618075026191",
"status": "COMPLETED",
"status_changed_ts": "1614621848547137",
"tags": [
"build_address:luci.chromium.ci/win-asan/17577",
"builder:win-asan",
"buildset:commit/git/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8898c11ab30f9c0cc7453c87af8cf8f7f623c94c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019374023221971504",
"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/8853943961086797088/+/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:52078e11bb11c310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614621848608547",
"url": "https://ci.chromium.org/b/8853943961086797088",
"utcnow_ts": "1614621849347340"
}
}
[18:04:09.359] Invocation finished in 1h3m3.16006347s with status SUCCEEDED