[11:25:30.599] New invocation is queued and will start shortly
[11:25:31.825] Starting the invocation (attempt 1)
[11:25:31.863] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:25:31.863] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:25:31.863] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025555730659975296",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025555730659975296",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"gitiles_ref:refs/heads/master"
]
}
[11:25:32.852] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608722731987234",
"id": "8860125668542123568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608722732669861",
"tags": [
"build_address:luci.chromium.ci/win-asan/16406",
"builder:win-asan",
"buildset:commit/git/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025555730659975296",
"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/8860125668542123568/+/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": "1608722732669975",
"url": "https://ci.chromium.org/b/8860125668542123568",
"utcnow_ts": "1608722732841446"
}
}
[11:25:32.852] Task URL: https://ci.chromium.org/b/8860125668542123568
[11:25:32.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:2:0) after 1m0s
[11:25:36.038] Received PubSub notification, asking Buildbucket for the build status
[11:25:36.114] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:32.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:2:0)
[11:26:32.875] Timer tick, asking Buildbucket for the build status
[11:26:32.980] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:32.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:4:0) after 1m0s
[11:27:32.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:4:0)
[11:27:32.973] Timer tick, asking Buildbucket for the build status
[11:27:33.339] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:33.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:5:0) after 1m0s
[11:28:33.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:5:0)
[11:28:33.358] Timer tick, asking Buildbucket for the build status
[11:28:33.489] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:33.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:6:0) after 1m0s
[11:29:33.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:6:0)
[11:29:33.518] Timer tick, asking Buildbucket for the build status
[11:29:33.798] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:33.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:7:0) after 1m0s
[11:30:33.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:7:0)
[11:30:33.885] Timer tick, asking Buildbucket for the build status
[11:30:34.006] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:34.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:8:0) after 1m0s
[11:31:34.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:8:0)
[11:31:34.037] Timer tick, asking Buildbucket for the build status
[11:31:34.141] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:34.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:9:0) after 1m0s
[11:32:34.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:9:0)
[11:32:34.218] Timer tick, asking Buildbucket for the build status
[11:32:34.318] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:34.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:10:0) after 1m0s
[11:33:34.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:10:0)
[11:33:34.312] Timer tick, asking Buildbucket for the build status
[11:33:34.397] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:34.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:11:0) after 1m0s
[11:34:34.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:11:0)
[11:34:34.482] Timer tick, asking Buildbucket for the build status
[11:34:34.555] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:34.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:12:0) after 1m0s
[11:35:34.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:12:0)
[11:35:34.623] Timer tick, asking Buildbucket for the build status
[11:35:34.789] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:34.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:13:0) after 1m0s
[11:36:34.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:13:0)
[11:36:34.816] Timer tick, asking Buildbucket for the build status
[11:36:34.936] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:34.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:14:0) after 1m0s
[11:37:34.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:14:0)
[11:37:34.998] Timer tick, asking Buildbucket for the build status
[11:37:35.578] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:35.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:15:0) after 1m0s
[11:38:35.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:15:0)
[11:38:35.628] Timer tick, asking Buildbucket for the build status
[11:38:36.209] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:36.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:16:0) after 1m0s
[11:39:36.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:16:0)
[11:39:36.371] Timer tick, asking Buildbucket for the build status
[11:39:36.489] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:36.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:17:0) after 1m0s
[11:40:36.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:17:0)
[11:40:36.587] Timer tick, asking Buildbucket for the build status
[11:40:36.910] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:36.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:18:0) after 1m0s
[11:41:37.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:18:0)
[11:41:37.034] Timer tick, asking Buildbucket for the build status
[11:41:37.691] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:37.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:19:0) after 1m0s
[11:42:37.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:19:0)
[11:42:37.771] Timer tick, asking Buildbucket for the build status
[11:42:37.840] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:37.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:20:0) after 1m0s
[11:43:37.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:20:0)
[11:43:37.913] Timer tick, asking Buildbucket for the build status
[11:43:38.063] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:38.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:21:0) after 1m0s
[11:44:38.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:21:0)
[11:44:38.037] Timer tick, asking Buildbucket for the build status
[11:44:38.169] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:38.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:22:0) after 1m0s
[11:45:38.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:22:0)
[11:45:38.218] Timer tick, asking Buildbucket for the build status
[11:45:38.315] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:38.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:23:0) after 1m0s
[11:46:38.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:23:0)
[11:46:38.303] Timer tick, asking Buildbucket for the build status
[11:46:38.382] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:38.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:24:0) after 1m0s
[11:47:38.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:24:0)
[11:47:38.412] Timer tick, asking Buildbucket for the build status
[11:47:39.034] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:39.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:25:0) after 1m0s
[11:48:39.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:25:0)
[11:48:39.088] Timer tick, asking Buildbucket for the build status
[11:48:39.195] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:39.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:26:0) after 1m0s
[11:49:39.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:26:0)
[11:49:39.234] Timer tick, asking Buildbucket for the build status
[11:49:39.343] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:39.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:27:0) after 1m0s
[11:50:39.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:27:0)
[11:50:39.372] Timer tick, asking Buildbucket for the build status
[11:50:39.501] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:39.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:28:0) after 1m0s
[11:51:39.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:28:0)
[11:51:39.572] Timer tick, asking Buildbucket for the build status
[11:51:39.907] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:39.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:29:0) after 1m0s
[11:52:39.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:29:0)
[11:52:39.928] Timer tick, asking Buildbucket for the build status
[11:52:39.990] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:39.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:30:0) after 1m0s
[11:53:40.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:30:0)
[11:53:40.089] Timer tick, asking Buildbucket for the build status
[11:53:40.302] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:40.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:31:0) after 1m0s
[11:54:40.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:31:0)
[11:54:40.336] Timer tick, asking Buildbucket for the build status
[11:54:40.649] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:40.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:32:0) after 1m0s
[11:55:40.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:32:0)
[11:55:40.673] Timer tick, asking Buildbucket for the build status
[11:55:40.889] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:40.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:33:0) after 1m0s
[11:56:40.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:33:0)
[11:56:40.944] Timer tick, asking Buildbucket for the build status
[11:56:41.042] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:41.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:34:0) after 1m0s
[11:57:41.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:34:0)
[11:57:41.052] Timer tick, asking Buildbucket for the build status
[11:57:41.577] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:41.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:35:0) after 1m0s
[11:58:41.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:35:0)
[11:58:41.605] Timer tick, asking Buildbucket for the build status
[11:58:41.687] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:41.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:36:0) after 1m0s
[11:59:41.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:36:0)
[11:59:41.772] Timer tick, asking Buildbucket for the build status
[11:59:41.843] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:41.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:37:0) after 1m0s
[12:00:41.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:37:0)
[12:00:41.878] Timer tick, asking Buildbucket for the build status
[12:00:42.060] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:42.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:38:0) after 1m0s
[12:01:42.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:38:0)
[12:01:42.078] Timer tick, asking Buildbucket for the build status
[12:01:42.165] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:42.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:39:0) after 1m0s
[12:02:42.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:39:0)
[12:02:42.205] Timer tick, asking Buildbucket for the build status
[12:02:42.350] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:42.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:40:0) after 1m0s
[12:03:42.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:40:0)
[12:03:42.413] Timer tick, asking Buildbucket for the build status
[12:03:42.535] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:42.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:41:0) after 1m0s
[12:04:42.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:41:0)
[12:04:42.572] Timer tick, asking Buildbucket for the build status
[12:04:42.721] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:42.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:42:0) after 1m0s
[12:05:42.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:42:0)
[12:05:42.743] Timer tick, asking Buildbucket for the build status
[12:05:42.933] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:42.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:43:0) after 1m0s
[12:06:42.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:43:0)
[12:06:42.963] Timer tick, asking Buildbucket for the build status
[12:06:43.102] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:43.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:44:0) after 1m0s
[12:07:43.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:44:0)
[12:07:43.159] Timer tick, asking Buildbucket for the build status
[12:07:43.237] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:43.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:45:0) after 1m0s
[12:08:43.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:45:0)
[12:08:43.299] Timer tick, asking Buildbucket for the build status
[12:08:43.411] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:43.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:46:0) after 1m0s
[12:09:43.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:46:0)
[12:09:43.429] Timer tick, asking Buildbucket for the build status
[12:09:43.535] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:43.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:47:0) after 1m0s
[12:10:43.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:47:0)
[12:10:43.522] Timer tick, asking Buildbucket for the build status
[12:10:43.653] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:43.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:48:0) after 1m0s
[12:11:43.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:48:0)
[12:11:43.673] Timer tick, asking Buildbucket for the build status
[12:11:43.789] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:43.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:49:0) after 1m0s
[12:12:43.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:49:0)
[12:12:43.814] Timer tick, asking Buildbucket for the build status
[12:12:43.878] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:43.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:50:0) after 1m0s
[12:13:43.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:50:0)
[12:13:43.872] Timer tick, asking Buildbucket for the build status
[12:13:44.204] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:44.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:51:0) after 1m0s
[12:14:44.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:51:0)
[12:14:44.208] Timer tick, asking Buildbucket for the build status
[12:14:44.415] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:44.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:52:0) after 1m0s
[12:15:44.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:52:0)
[12:15:44.471] Timer tick, asking Buildbucket for the build status
[12:15:44.605] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:44.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:53:0) after 1m0s
[12:16:44.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:53:0)
[12:16:44.583] Timer tick, asking Buildbucket for the build status
[12:16:44.653] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:44.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:54:0) after 1m0s
[12:17:44.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:54:0)
[12:17:44.725] Timer tick, asking Buildbucket for the build status
[12:17:44.782] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:44.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:55:0) after 1m0s
[12:18:44.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:55:0)
[12:18:44.803] Timer tick, asking Buildbucket for the build status
[12:18:44.883] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:44.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:56:0) after 1m0s
[12:19:44.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:56:0)
[12:19:44.906] Timer tick, asking Buildbucket for the build status
[12:19:45.030] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:45.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:57:0) after 1m0s
[12:20:45.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:57:0)
[12:20:45.051] Timer tick, asking Buildbucket for the build status
[12:20:45.197] Build 8860125668542123568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:45.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025555730659975296:58:0) after 1m0s
[12:21:22.163] Received PubSub notification, asking Buildbucket for the build status
[12:21:22.258] Build 8860125668542123568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:21:22.258] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608726080563845",
"created_by": "project:chromium",
"created_ts": "1608722731987234",
"id": "8860125668542123568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\"}}",
"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\": \"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16406, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"d447829c19fd6275b94676aab20e303c224fe126\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\", \"got_revision_cp\": \"refs/heads/master@{#839091}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"5af8c7382f71c6735d1d75cbd056e78e7ccd8862\", \"got_v8_revision_cp\": \"refs/heads/8.9.186@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"a8f825cc0f19d524d14daa8759a2d47de2c0a42a\", \"swarm_hashes_refs/heads/master(at){#839091}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"9c25cf576cee4b5d6304917b3460cd841b85afa9\", \"angle_unittests\": \"571027a9beb72b2b4a56911580ec4a89ba604851\", \"app_shell_unittests\": \"fb6ebff94ee6dc5e6ac9fd48c4270e6ab244c971\", \"aura_unittests\": \"66e0fa61c402298ff9f9d9391cf3ad6b6be4177d\", \"base_unittests\": \"ff09bb7d5f2b63139b7ee1626375a378dd7e50f0\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"3db744e09115bc30cea29b88852f51d636f4ba7d\", \"blink_fuzzer_unittests\": \"55ab026dcf0b895c9f250850bd890c054f8acbaa\", \"blink_heap_unittests\": \"7729b13024e9cda97f627631c94383e23af10260\", \"blink_platform_unittests\": \"880e11c273c400a8f6c9716dce74c76faa4c518f\", \"blink_unittests\": \"1e962f573a206e73ac0a0fa6844b718a6c629789\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"92ec611b74b2a73aba1d9195a99e9d6785fb8bdd\", \"capture_unittests\": \"6baf52caca9e23026e2c5e5251f5d7eda81f48b9\", \"cast_unittests\": \"a2aa57a1cb67e4d9f04bc1edd162f1cbda0f14c1\", \"cc_unittests\": \"4843d66f185324f440843406c22a5d278ecbf758\", \"chrome_app_unittests\": \"b5f13c2d06f4a6f7cfcf974cc4620c256c999288\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"a97d99bbff2d3c5945ed7aff2328498049a2e901\", \"chromedriver_unittests\": \"4ab8cb6cdf650853ed728681b5258bf7b72b8983\", \"components_browsertests\": \"ffbb71012154131fcf389055884d3f74e782af08\", \"components_unittests\": \"0ad2caa881215260ff64db5be067bb1a7b276e78\", \"compositor_unittests\": \"c876234675c59dac2162a099181e03e11d27468c\", \"content_browsertests\": \"df3d1e6fdfd2ee4e6822744497f295124b531ee3\", \"content_unittests\": \"5f5d34f52770e8a4bb40f365c0977176d93685b5\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"f0eecf7f56a477c97acf46ba7d9b333ebfecd7e4\", \"device_unittests\": \"e24b10062dc061a21c0a6a6b23d9debf64f6bca9\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"f80b4456bef20b8dfd7814ad927e3a0c2d406c47\", \"events_unittests\": \"d62bd5e9f38333e493d0a4c6410ef141c59c2b96\", \"extensions_browsertests\": \"ba02d4a6b57f3aac721bf245612b27663a9775c1\", \"extensions_unittests\": \"78d78d4c198a1acdd47fc9f6188d692a9ff35ff0\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"5e789e5c35dd9cc486b7125a7105d366eee034ac\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"a40b361c1a1495acb82b99b15328090331b06966\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"e343ad00e4d9d3067bd5bc7dd4b21b1c0c2aa8c4\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"f752f2b9e09b40a5e4a55da30847e129124dac64\", \"headless_unittests\": \"e99bdb80375ed699210e89fbe7a02fb9753f44d6\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"c0a096b2ad5e3ef118c555ddc096a97b359602f0\", \"interactive_ui_tests\": \"4d5f6f3adf8d58a11fda4b254224a1e025f9524b\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"6f81b124ba8b7d32ec4db9bd02243fc0ec195ebb\", \"media_unittests\": \"741b7528ece72bdcd9163ac956afa73a192d6d75\", \"message_center_unittests\": \"2b22d186b3f507251865e0eab288f95283af966e\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"48b2fdae4040884d57fb569ec4fda1e1415e56c0\", \"nacl_loader_unittests\": \"3377aecbc57f3dfd4398a5326074e47c8641e075\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"d940f160321ef646766b6d91773d69940a778215\", \"pdf_unittests\": \"d99a6890aa5a48039b3770fdc5eaa5a108a09180\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"b6ca01995605f2acb563ad5440d3b5af6ea3c989\", \"printing_unittests\": \"635a60d3faf6915d3d1adc736c9ed0663b584f7d\", \"remoting_unittests\": \"9cc0ee6c35e4d8b1efd17b135c2c0fc53eed9580\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"213a8f2f683419ed16a2724f10ba882295c871e8\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"72cf6c1e86fb36fc141b52c0c1e41150838d8011\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"5fdba06de5e313b5c25e8ae2fd52bc0c60f1bab2\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"a91a312b9a5dad147f1b49d36efd726ad2d83601\", \"sync_integration_tests\": \"5bc1b7254210cbb54688ba9c82e018d4b5240d74\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"d443564944c0df95efa46cb609a23eca30d61146\", \"ui_touch_selection_unittests\": \"fad905adc771ae651bed83d07cdd7acff764d62e\", \"unit_tests\": \"525de3cb5fc42b3c8a77111f0cf437029fb3e069\", \"updater_tests\": \"2af65c95b09e485903a7ea5eeea61d28446e6576\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"4b8644dd63c75f01f24361f588c1178d4d61ceea\", \"viz_unittests\": \"9451d6a714264f6a51f427d9077726ba310561a5\", \"vr_common_unittests\": \"14cfc9b0061c8211e6c7e3566ab42fed6fccfc7b\", \"vr_pixeltests\": \"72a8d88542b81a962fcdf924668efab2af588415\", \"weblayer_browsertests\": \"050b82e0e1535ea9711d19546208e5dc4f299451\", \"weblayer_unittests\": \"f306fee524f4bb72914083c921588c5870d91b9c\", \"wm_unittests\": \"524f1039dc57a8ee38717c5eec4dcbe51c9f101d\", \"wtf_unittests\": \"9fcfc07bf261dbfd4c00be248dd40f6264a10ebe\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"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-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"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": "1608722735140510",
"status": "COMPLETED",
"status_changed_ts": "1608726080989021",
"tags": [
"build_address:luci.chromium.ci/win-asan/16406",
"builder:win-asan",
"buildset:commit/git/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a8f825cc0f19d524d14daa8759a2d47de2c0a42a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025555730659975296",
"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/8860125668542123568/+/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:50a82a60365eb510",
"user_agent:luci-scheduler"
],
"updated_ts": "1608726081015246",
"url": "https://ci.chromium.org/b/8860125668542123568",
"utcnow_ts": "1608726082244859"
}
}
[12:21:22.258] Invocation finished in 55m51.674014935s with status SUCCEEDED