[16:44:05.878] New invocation is queued and will start shortly
[16:44:07.034] Starting the invocation (attempt 1)
[16:44:07.072] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:44:07.074] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:44:07.074] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026894641333263008",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"558afe4a9cb262f5201eb8522d7928b8e8974720\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026894641333263008",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/558afe4a9cb262f5201eb8522d7928b8e8974720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/558afe4a9cb262f5201eb8522d7928b8e8974720",
"gitiles_ref:refs/heads/master"
]
}
[16:44:07.738] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607445847204365",
"id": "8861464579280000752",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"558afe4a9cb262f5201eb8522d7928b8e8974720\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607445847629490",
"tags": [
"build_address:luci.chromium.ci/win-asan/16102",
"builder:win-asan",
"buildset:commit/git/558afe4a9cb262f5201eb8522d7928b8e8974720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/558afe4a9cb262f5201eb8522d7928b8e8974720",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026894641333263008",
"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/8861464579280000752/+/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": "1607445847629594",
"url": "https://ci.chromium.org/b/8861464579280000752",
"utcnow_ts": "1607445847730814"
}
}
[16:44:07.738] Task URL: https://ci.chromium.org/b/8861464579280000752
[16:44:07.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:2:0) after 1m0s
[16:44:17.678] Received PubSub notification, asking Buildbucket for the build status
[16:44:17.757] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:07.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:2:0)
[16:45:07.732] Timer tick, asking Buildbucket for the build status
[16:45:07.896] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:07.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:4:0) after 1m0s
[16:46:07.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:4:0)
[16:46:07.965] Timer tick, asking Buildbucket for the build status
[16:46:08.097] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:08.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:5:0) after 1m0s
[16:47:08.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:5:0)
[16:47:08.215] Timer tick, asking Buildbucket for the build status
[16:47:08.722] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:08.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:6:0) after 1m0s
[16:48:08.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:6:0)
[16:48:08.757] Timer tick, asking Buildbucket for the build status
[16:48:08.954] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:08.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:7:0) after 1m0s
[16:49:09.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:7:0)
[16:49:09.095] Timer tick, asking Buildbucket for the build status
[16:49:09.197] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:09.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:8:0) after 1m0s
[16:50:09.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:8:0)
[16:50:09.369] Timer tick, asking Buildbucket for the build status
[16:50:09.476] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:09.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:9:0) after 1m0s
[16:51:09.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:9:0)
[16:51:09.603] Timer tick, asking Buildbucket for the build status
[16:51:09.673] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:09.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:10:0) after 1m0s
[16:52:10.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:10:0)
[16:52:10.016] Timer tick, asking Buildbucket for the build status
[16:52:10.085] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:10.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:11:0) after 1m0s
[16:53:10.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:11:0)
[16:53:10.254] Timer tick, asking Buildbucket for the build status
[16:53:10.329] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:10.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:12:0) after 1m0s
[16:54:10.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:12:0)
[16:54:10.324] Timer tick, asking Buildbucket for the build status
[16:54:10.475] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:10.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:13:0) after 1m0s
[16:55:10.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:13:0)
[16:55:10.498] Timer tick, asking Buildbucket for the build status
[16:55:10.614] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:10.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:14:0) after 1m0s
[16:56:10.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:14:0)
[16:56:10.731] Timer tick, asking Buildbucket for the build status
[16:56:10.796] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:10.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:15:0) after 1m0s
[16:57:10.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:15:0)
[16:57:10.901] Timer tick, asking Buildbucket for the build status
[16:57:10.960] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:10.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:16:0) after 1m0s
[16:58:11.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:16:0)
[16:58:11.049] Timer tick, asking Buildbucket for the build status
[16:58:11.161] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:11.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:17:0) after 1m0s
[16:59:11.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:17:0)
[16:59:11.185] Timer tick, asking Buildbucket for the build status
[16:59:11.805] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:11.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:18:0) after 1m0s
[17:00:11.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:18:0)
[17:00:11.864] Timer tick, asking Buildbucket for the build status
[17:00:12.533] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:12.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:19:0) after 1m0s
[17:01:12.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:19:0)
[17:01:12.615] Timer tick, asking Buildbucket for the build status
[17:01:12.708] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:12.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:20:0) after 1m0s
[17:02:12.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:20:0)
[17:02:12.757] Timer tick, asking Buildbucket for the build status
[17:02:12.824] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:12.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:21:0) after 1m0s
[17:03:12.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:21:0)
[17:03:12.856] Timer tick, asking Buildbucket for the build status
[17:03:12.979] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:12.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:22:0) after 1m0s
[17:04:13.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:22:0)
[17:04:13.015] Timer tick, asking Buildbucket for the build status
[17:04:13.167] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:13.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:23:0) after 1m0s
[17:05:13.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:23:0)
[17:05:13.190] Timer tick, asking Buildbucket for the build status
[17:05:13.297] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:13.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:24:0) after 1m0s
[17:06:13.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:24:0)
[17:06:13.377] Timer tick, asking Buildbucket for the build status
[17:06:13.515] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:13.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:25:0) after 1m0s
[17:07:13.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:25:0)
[17:07:13.538] Timer tick, asking Buildbucket for the build status
[17:07:13.721] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:13.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:26:0) after 1m0s
[17:08:13.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:26:0)
[17:08:13.745] Timer tick, asking Buildbucket for the build status
[17:08:14.044] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:14.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:27:0) after 1m0s
[17:09:14.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:27:0)
[17:09:14.054] Timer tick, asking Buildbucket for the build status
[17:09:14.202] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:14.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:28:0) after 1m0s
[17:10:14.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:28:0)
[17:10:14.218] Timer tick, asking Buildbucket for the build status
[17:10:14.928] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:14.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:29:0) after 1m0s
[17:11:15.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:29:0)
[17:11:15.003] Timer tick, asking Buildbucket for the build status
[17:11:15.137] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:15.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:30:0) after 1m0s
[17:12:15.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:30:0)
[17:12:15.161] Timer tick, asking Buildbucket for the build status
[17:12:15.317] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:15.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:31:0) after 1m0s
[17:13:15.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:31:0)
[17:13:15.329] Timer tick, asking Buildbucket for the build status
[17:13:15.411] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:15.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:32:0) after 1m0s
[17:14:15.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:32:0)
[17:14:15.481] Timer tick, asking Buildbucket for the build status
[17:14:15.623] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:15.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:33:0) after 1m0s
[17:15:15.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:33:0)
[17:15:15.672] Timer tick, asking Buildbucket for the build status
[17:15:16.057] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:16.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:34:0) after 1m0s
[17:16:16.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:34:0)
[17:16:16.169] Timer tick, asking Buildbucket for the build status
[17:16:16.372] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:16.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:35:0) after 1m0s
[17:17:16.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:35:0)
[17:17:16.411] Timer tick, asking Buildbucket for the build status
[17:17:16.533] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:16.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:36:0) after 1m0s
[17:18:16.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:36:0)
[17:18:16.576] Timer tick, asking Buildbucket for the build status
[17:18:17.007] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:37:0) after 1m0s
[17:19:17.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:37:0)
[17:19:17.053] Timer tick, asking Buildbucket for the build status
[17:19:17.309] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:17.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:38:0) after 1m0s
[17:20:17.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:38:0)
[17:20:17.330] Timer tick, asking Buildbucket for the build status
[17:20:17.835] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:17.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:39:0) after 1m0s
[17:21:17.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:39:0)
[17:21:17.870] Timer tick, asking Buildbucket for the build status
[17:21:18.153] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:18.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:40:0) after 1m0s
[17:22:18.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:40:0)
[17:22:18.195] Timer tick, asking Buildbucket for the build status
[17:22:18.280] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:18.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:41:0) after 1m0s
[17:23:18.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:41:0)
[17:23:18.348] Timer tick, asking Buildbucket for the build status
[17:23:18.434] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:18.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:42:0) after 1m0s
[17:24:18.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:42:0)
[17:24:18.455] Timer tick, asking Buildbucket for the build status
[17:24:18.547] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:18.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:43:0) after 1m0s
[17:25:18.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:43:0)
[17:25:18.595] Timer tick, asking Buildbucket for the build status
[17:25:18.721] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:18.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:44:0) after 1m0s
[17:26:18.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:44:0)
[17:26:18.745] Timer tick, asking Buildbucket for the build status
[17:26:18.878] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:18.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:45:0) after 1m0s
[17:27:18.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:45:0)
[17:27:18.893] Timer tick, asking Buildbucket for the build status
[17:27:19.280] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:19.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:46:0) after 1m0s
[17:28:19.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:46:0)
[17:28:19.312] Timer tick, asking Buildbucket for the build status
[17:28:19.412] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:19.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:47:0) after 1m0s
[17:29:19.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:47:0)
[17:29:19.474] Timer tick, asking Buildbucket for the build status
[17:29:19.613] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:19.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:48:0) after 1m0s
[17:30:19.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:48:0)
[17:30:19.638] Timer tick, asking Buildbucket for the build status
[17:30:20.440] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:20.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:49:0) after 1m0s
[17:31:20.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:49:0)
[17:31:20.533] Timer tick, asking Buildbucket for the build status
[17:31:20.664] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:20.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:50:0) after 1m0s
[17:32:20.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:50:0)
[17:32:20.688] Timer tick, asking Buildbucket for the build status
[17:32:21.123] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:21.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:51:0) after 1m0s
[17:33:21.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:51:0)
[17:33:21.252] Timer tick, asking Buildbucket for the build status
[17:33:21.450] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:21.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:52:0) after 1m0s
[17:34:21.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:52:0)
[17:34:21.424] Timer tick, asking Buildbucket for the build status
[17:34:21.581] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:21.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:53:0) after 1m0s
[17:35:21.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:53:0)
[17:35:21.603] Timer tick, asking Buildbucket for the build status
[17:35:21.689] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:21.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:54:0) after 1m0s
[17:36:21.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:54:0)
[17:36:21.759] Timer tick, asking Buildbucket for the build status
[17:36:21.835] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:21.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:55:0) after 1m0s
[17:37:21.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:55:0)
[17:37:21.838] Timer tick, asking Buildbucket for the build status
[17:37:21.897] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:21.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:56:0) after 1m0s
[17:38:21.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:56:0)
[17:38:21.938] Timer tick, asking Buildbucket for the build status
[17:38:22.193] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:22.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:57:0) after 1m0s
[17:39:22.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:57:0)
[17:39:22.283] Timer tick, asking Buildbucket for the build status
[17:39:22.396] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:22.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:58:0) after 1m0s
[17:40:22.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:58:0)
[17:40:22.416] Timer tick, asking Buildbucket for the build status
[17:40:22.504] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:22.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:59:0) after 1m0s
[17:41:22.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:59:0)
[17:41:22.525] Timer tick, asking Buildbucket for the build status
[17:41:23.077] Build 8861464579280000752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:23.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026894641333263008:60:0) after 1m0s
[17:42:09.709] Received PubSub notification, asking Buildbucket for the build status
[17:42:09.852] Build 8861464579280000752: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:42:09.852] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607449328208922",
"created_by": "project:chromium",
"created_ts": "1607445847204365",
"id": "8861464579280000752",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"558afe4a9cb262f5201eb8522d7928b8e8974720\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"558afe4a9cb262f5201eb8522d7928b8e8974720\", \"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-us-central1-b-32-1-z6mr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16102, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4f26a81e8710a41a7be6761322115d4c39b1fd24\", \"got_dawn_revision\": \"61355d416d9c568bcfbed6518516fbe5ef219e42\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"558afe4a9cb262f5201eb8522d7928b8e8974720\", \"got_revision_cp\": \"refs/heads/master@{#834729}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"53a11922d89285a3f15744d59e0cfcff2dd33f91\", \"got_v8_revision_cp\": \"refs/heads/8.9.120@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"558afe4a9cb262f5201eb8522d7928b8e8974720\", \"swarm_hashes_refs/heads/master(at){#834729}_without_patch\": {\"absl_hardening_tests\": \"2f48598522621c87886edf03118514e4833559d2\", \"accessibility_unittests\": \"d02e62183578ac3940e799c330d74e6bafaa4ced\", \"angle_unittests\": \"56b690e42c42beb27b76d0754a7f00baa6fd5f3a\", \"app_shell_unittests\": \"4f9165903d3c8b4724e6cde1e3fd24d07fda4c73\", \"aura_unittests\": \"a61a753d9ec4903fe51369af2594ced770ffe103\", \"base_unittests\": \"157e973546e006c61ae93cbe9db4dc5729c2b74b\", \"base_util_unittests\": \"589452762fb420728f1a0dc850842c8ea8d45bdb\", \"blink_common_unittests\": \"8977e6acb72e9c598cb972bba868abd93764aed9\", \"blink_fuzzer_unittests\": \"2e52ccbfbe10559e74716456c3839f3bbdaeedf7\", \"blink_heap_unittests\": \"d82fe6fd4f0d971f2f79674081d470e444b37b74\", \"blink_platform_unittests\": \"e3a3321ec609e335dd40410ff8099319c7f18ca0\", \"blink_unittests\": \"24b6f7dfded08bf082e098a937652d0f8def044b\", \"boringssl_crypto_tests\": \"93c1021797e45dea907be7f47ed420353fcf0278\", \"boringssl_ssl_tests\": \"dc08da3eca46433ab1978383a04323505c2f71a1\", \"browser_switcher_bho_unittests\": \"a75b90f2c894b66e990c63c7ca627e03f9de3017\", \"browser_tests\": \"84d999146159e6f6b5df8155ea5a241180be2dd9\", \"capture_unittests\": \"c5e30192065c2c0c61ad983f379232fc7160b229\", \"cast_unittests\": \"50946a583be45fc286268edba3d1d3b82c5c7700\", \"cc_unittests\": \"ee32be51840cdcb9443df47d4bea4a52e270cdbc\", \"chrome_app_unittests\": \"fd4f8a32219709106bb3d10493d0c0ff78bd9c7b\", \"chrome_cleaner_unittests\": \"aaf6c8587c2b451b44629d6c76eb386c5c43c08c\", \"chrome_elf_unittests\": \"7ad289bad7700427e639efded78a16a275efff13\", \"chromedriver_unittests\": \"6184db73a98d6807090de9408b3719f6ddf3184a\", \"components_browsertests\": \"0e9d15e562801a950cf1361887f26e0a8944395c\", \"components_unittests\": \"e277e17ab19163f07201c6b6b8781828c2a4d633\", \"compositor_unittests\": \"ccce75e5dab0f1aadd720f5ba8396fe7164d9e92\", \"content_browsertests\": \"31d94ae588fba53948f5b82e018bb0a4e050104a\", \"content_unittests\": \"8498498e3fe4af9051eabf621b28dc93da236421\", \"courgette_unittests\": \"8f418bbd71cdbf2baf42c46962004f68dc715c37\", \"crashpad_tests\": \"568e1be747b46173c575cfc7a1260eee60873604\", \"cronet_tests\": \"7a8f161e6566689d7227f0220fff7ca92ca18c62\", \"cronet_unittests\": \"43126e7220b82a1ab0e184f04696b1f246dd326e\", \"crypto_unittests\": \"e71ce40856d68627c7ddfc6b8b1f759ea43965f0\", \"delayloads_unittests\": \"ebeaecc2d2f0a0178a934a3043510fbf7622e3eb\", \"device_unittests\": \"80761b5b6c1f8a6dea512c6eebf206529ba80608\", \"display_unittests\": \"13e0116745c5057eddb4fca3aca35150a59f826c\", \"elevation_service_unittests\": \"e1019e4641189fe50f6cd349d2692c602a5f573b\", \"events_unittests\": \"a832c968bb2a5b149a3f28d8fe13a2243fbcf36e\", \"extensions_browsertests\": \"ce4b485503c7217e5126da0a6c0077feed0122fc\", \"extensions_unittests\": \"e2436de8ebe03c0d0cbd890d29640a0f1457631b\", \"filesystem_service_unittests\": \"7fd836c823dd7c3edc06cb98997cb9b591f3511f\", \"gcm_unit_tests\": \"ca0223bbd7a6ad130d8e397c7a23251424ae4154\", \"gcp_unittests\": \"8130a478eec86548d62bf9d87b7361e4cd3491b2\", \"gfx_unittests\": \"5223d3c0665aa03a32a8b4aa2d28e457b7b39ae9\", \"gin_unittests\": \"7c261d22e9a355c946cc0826636d8d6be33adc45\", \"google_apis_unittests\": \"822277c8459fad294d1c60fc468c271bbd407b49\", \"gpu_unittests\": \"ecf881d909953061564f2d496a1caab476aff01c\", \"gwp_asan_unittests\": \"227915b6f90ee81dcbf226dbcc48ed0e77b39873\", \"headless_browsertests\": \"d8174f10a3b84de024dc58d7ab18669352de0641\", \"headless_unittests\": \"67255c330ac54734a45f09fccd5080123ed7f5ac\", \"install_static_unittests\": \"8bb8d0aae532c6d5dde1e4213a05016774f54db6\", \"installer_util_unittests\": \"755edfae4857191a7a350db387ce212f27235d90\", \"interactive_ui_tests\": \"5bcd414c0fd7ae769cdb4cb662559579fed882b5\", \"ipc_tests\": \"e5ebe5f7419aeb9dbacfb748a368fd6f4a38cd43\", \"jingle_unittests\": \"43245ddf22acda412566251fd878b7fa92c94047\", \"latency_unittests\": \"48fa919869c725a298f546af83819686a80b2e27\", \"libjingle_xmpp_unittests\": \"b6d5c4fac8e406364dfc867be6ca29ab8a30e7c5\", \"liburlpattern_unittests\": \"fbf454f9656925a9226679522f056b020b5e7a53\", \"media_blink_unittests\": \"d22aba35d509e12336d133412e6b0f78a37fd1e9\", \"media_unittests\": \"276580c2750bbd4abf0560b0120e23d7863a3855\", \"message_center_unittests\": \"33fcbe862d97a15bb6f5db9f16c4cda4d21769ea\", \"midi_unittests\": \"09194330081ba93fde78e53317b9dc4da276defb\", \"mojo_core_unittests\": \"b295b3e25393b6bec1df1931d997b19a19c6d1e6\", \"mojo_unittests\": \"bb60d052e9bfa037c83071ff0a06b724329e821c\", \"nacl_loader_unittests\": \"8cca00a106bd901dd38bc9b437ddf94c634e08ea\", \"native_theme_unittests\": \"165cafc0ee5195b58a87ed7ed5ec07029ffa8f18\", \"net_unittests\": \"80213f5e9dee5b05d256a09b8d8f485b458315de\", \"notification_helper_unittests\": \"6b05a419dfb949ab51bd62384de5901746a76b64\", \"pdf_unittests\": \"e1a2293de75789da71d074808be1b5e17d2bdf57\", \"perfetto_unittests\": \"7941ae818e497d76045506ac9a84d62406a5b7b3\", \"ppapi_unittests\": \"700e79b40ea7007d9b9c7dc003ec6b2a40dbfad7\", \"printing_unittests\": \"76546b0909412447a8f37fef31f10232202bd3e5\", \"remoting_unittests\": \"1ab93a5e420843d945dbb127e6be1e042e0252c9\", \"sbox_integration_tests\": \"a9dcd9a93055c4386a50fffc87820e3ef1b008f0\", \"sbox_unittests\": \"59e6429be4189b82253dde5139efbfb1daca8cf1\", \"sbox_validation_tests\": \"81255c0458bab267d8335512835a16e9eccdebd2\", \"service_manager_unittests\": \"3749cb9ba7c3390ed47e08f85f4438529fad5766\", \"services_unittests\": \"54b5419b14c8e6b5a5e2b37b539e3b27320a54f0\", \"setup_unittests\": \"38cf92c3a40ed78c00b1164de900389369644970\", \"shell_dialogs_unittests\": \"6bfd0d30307bc4216836748c26af41c1e7c12ed7\", \"skia_unittests\": \"569367c0989f4000bbe21b67167d91e5d4a04a03\", \"snapshot_unittests\": \"91b271bc1687b5b057d7d965d13662574eae2289\", \"sql_unittests\": \"70a62189b6cddbfebb5466d28a649707c65d9a2b\", \"storage_unittests\": \"91394abe6c4a61aa38e15ce8734e43f03eb612ea\", \"sync_integration_tests\": \"84c8499f663af23a7d6f0e0ff3981bf1c17e96c5\", \"traffic_annotation_auditor_unittests\": \"4f44dd6d6a32a9a2f276223857614bce475bfda5\", \"ui_base_unittests\": \"40ab92c57e85735402001163b222205b6ce79dfa\", \"ui_touch_selection_unittests\": \"ba681ea8404c282aed470efa9f8b4ed3ef75afc1\", \"unit_tests\": \"0892a5cd1516ddb42ff9de6a37d2e8dffbe50922\", \"updater_tests\": \"06350fb6c672d40f1e573bfec2b59d742e9603b8\", \"url_unittests\": \"b612bb8cfefe3f0290287dd5f85c340bb6fd5bf2\", \"views_unittests\": \"b8fa6b7d77cbe0cad2c001801c07cd979f066ace\", \"viz_unittests\": \"1fd3bddb7e12a11bc1f15adcf2d01042e0657baf\", \"vr_common_unittests\": \"7a1ade00bd6a1aeb896a027b64f628f1bc2e4d00\", \"vr_pixeltests\": \"3f262345fcfe1299dbac6f6c8e7a88268a52c27b\", \"weblayer_browsertests\": \"ec0eee6a7b8bfc0c645e19e9c2e89c227b1f06c9\", \"weblayer_unittests\": \"95982c9a6d5eb9aeee1088a8114e753c23cc63b3\", \"wm_unittests\": \"6512a3b73e72f397cc8cfe89a8bddf9f85b9d371\", \"wtf_unittests\": \"8e9cc370b9ce52cf252a035d8915701580ea5f31\", \"zlib_unittests\": \"2b1a6aa0dd79a978368cfbb9c045d29615bb49c9\", \"zucchini_unittests\": \"637e98e80f9e718a18d0435875e94b20a681d5e8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-z6mr\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607445856979707",
"status": "COMPLETED",
"status_changed_ts": "1607449328613615",
"tags": [
"build_address:luci.chromium.ci/win-asan/16102",
"builder:win-asan",
"buildset:commit/git/558afe4a9cb262f5201eb8522d7928b8e8974720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/558afe4a9cb262f5201eb8522d7928b8e8974720",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026894641333263008",
"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/8861464579280000752/+/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:505c0ec68fc68310",
"user_agent:luci-scheduler"
],
"updated_ts": "1607449328629067",
"url": "https://ci.chromium.org/b/8861464579280000752",
"utcnow_ts": "1607449329838328"
}
}
[17:42:09.853] Invocation finished in 58m3.989943468s with status SUCCEEDED