[07:45:54.311] New invocation is queued and will start shortly
[07:45:55.380] Starting the invocation (attempt 1)
[07:45:55.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:45:55.427] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:45:55.427] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028559246891086592",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028559246891086592",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"gitiles_ref:refs/heads/master"
]
}
[07:45:57.300] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605858356654733",
"id": "8863129183770945184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605858357166153",
"tags": [
"build_address:luci.chromium.ci/win-asan/15735",
"builder:win-asan",
"buildset:commit/git/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028559246891086592",
"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/8863129183770945184/+/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": "1605858357166294",
"url": "https://ci.chromium.org/b/8863129183770945184",
"utcnow_ts": "1605858357291137"
}
}
[07:45:57.300] Task URL: https://ci.chromium.org/b/8863129183770945184
[07:45:57.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:2:0) after 1m0s
[07:45:59.315] Received PubSub notification, asking Buildbucket for the build status
[07:45:59.428] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:57.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:2:0)
[07:46:57.323] Timer tick, asking Buildbucket for the build status
[07:46:57.494] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:57.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:4:0) after 1m0s
[07:47:57.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:4:0)
[07:47:57.515] Timer tick, asking Buildbucket for the build status
[07:47:57.619] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:57.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:5:0) after 1m0s
[07:48:57.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:5:0)
[07:48:57.653] Timer tick, asking Buildbucket for the build status
[07:48:58.008] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:58.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:6:0) after 1m0s
[07:49:57.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:6:0)
[07:49:57.978] Timer tick, asking Buildbucket for the build status
[07:49:58.075] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:58.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:7:0) after 1m0s
[07:50:58.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:7:0)
[07:50:58.078] Timer tick, asking Buildbucket for the build status
[07:50:58.132] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:58.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:8:0) after 1m0s
[07:51:58.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:8:0)
[07:51:58.178] Timer tick, asking Buildbucket for the build status
[07:51:59.219] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:59.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:9:0) after 1m0s
[07:52:59.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:9:0)
[07:52:59.252] Timer tick, asking Buildbucket for the build status
[07:52:59.406] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:59.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:10:0) after 1m0s
[07:53:59.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:10:0)
[07:53:59.434] Timer tick, asking Buildbucket for the build status
[07:53:59.577] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:59.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:11:0) after 1m0s
[07:54:59.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:11:0)
[07:54:59.600] Timer tick, asking Buildbucket for the build status
[07:54:59.776] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:59.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:12:0) after 1m0s
[07:55:59.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:12:0)
[07:55:59.853] Timer tick, asking Buildbucket for the build status
[07:55:59.969] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:59.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:13:0) after 1m0s
[07:57:00.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:13:0)
[07:57:00.079] Timer tick, asking Buildbucket for the build status
[07:57:00.525] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:00.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:14:0) after 1m0s
[07:58:00.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:14:0)
[07:58:00.577] Timer tick, asking Buildbucket for the build status
[07:58:00.668] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:00.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:15:0) after 1m0s
[07:59:01.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:15:0)
[07:59:01.016] Timer tick, asking Buildbucket for the build status
[07:59:01.129] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:01.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:16:0) after 1m0s
[08:00:01.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:16:0)
[08:00:01.178] Timer tick, asking Buildbucket for the build status
[08:00:01.378] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:01.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:17:0) after 1m0s
[08:01:01.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:17:0)
[08:01:01.455] Timer tick, asking Buildbucket for the build status
[08:01:01.666] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:01.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:18:0) after 1m0s
[08:02:01.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:18:0)
[08:02:01.866] Timer tick, asking Buildbucket for the build status
[08:02:01.951] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:01.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:19:0) after 1m0s
[08:03:02.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:19:0)
[08:03:02.075] Timer tick, asking Buildbucket for the build status
[08:03:02.301] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:02.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:20:0) after 1m0s
[08:04:02.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:20:0)
[08:04:02.410] Timer tick, asking Buildbucket for the build status
[08:04:02.765] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:21:0) after 1m0s
[08:05:02.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:21:0)
[08:05:02.843] Timer tick, asking Buildbucket for the build status
[08:05:03.446] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:03.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:22:0) after 1m0s
[08:06:03.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:22:0)
[08:06:03.501] Timer tick, asking Buildbucket for the build status
[08:06:03.645] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:03.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:23:0) after 1m0s
[08:07:03.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:23:0)
[08:07:03.771] Timer tick, asking Buildbucket for the build status
[08:07:04.015] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:04.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:24:0) after 1m0s
[08:08:04.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:24:0)
[08:08:04.016] Timer tick, asking Buildbucket for the build status
[08:08:04.109] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:25:0) after 1m0s
[08:09:04.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:25:0)
[08:09:04.162] Timer tick, asking Buildbucket for the build status
[08:09:04.225] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:04.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:26:0) after 1m0s
[08:10:04.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:26:0)
[08:10:04.248] Timer tick, asking Buildbucket for the build status
[08:10:04.390] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:04.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:27:0) after 1m0s
[08:11:04.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:27:0)
[08:11:04.632] Timer tick, asking Buildbucket for the build status
[08:11:04.898] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:04.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:28:0) after 1m0s
[08:12:05.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:28:0)
[08:12:05.002] Timer tick, asking Buildbucket for the build status
[08:12:05.178] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:05.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:29:0) after 1m0s
[08:13:05.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:29:0)
[08:13:05.178] Timer tick, asking Buildbucket for the build status
[08:13:05.353] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:05.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:30:0) after 1m0s
[08:14:05.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:30:0)
[08:14:05.435] Timer tick, asking Buildbucket for the build status
[08:14:06.029] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:06.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:31:0) after 1m0s
[08:15:06.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:31:0)
[08:15:06.306] Timer tick, asking Buildbucket for the build status
[08:15:06.477] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:06.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:32:0) after 1m0s
[08:16:06.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:32:0)
[08:16:06.516] Timer tick, asking Buildbucket for the build status
[08:16:06.714] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:06.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:33:0) after 1m0s
[08:17:06.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:33:0)
[08:17:06.785] Timer tick, asking Buildbucket for the build status
[08:17:07.006] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:07.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:34:0) after 1m0s
[08:18:07.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:34:0)
[08:18:07.066] Timer tick, asking Buildbucket for the build status
[08:18:07.277] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:07.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:35:0) after 1m0s
[08:19:07.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:35:0)
[08:19:07.381] Timer tick, asking Buildbucket for the build status
[08:19:07.594] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:07.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:36:0) after 1m0s
[08:20:07.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:36:0)
[08:20:07.664] Timer tick, asking Buildbucket for the build status
[08:20:07.799] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:37:0) after 1m0s
[08:21:07.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:37:0)
[08:21:07.895] Timer tick, asking Buildbucket for the build status
[08:21:08.139] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:08.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:38:0) after 1m0s
[08:22:08.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:38:0)
[08:22:08.166] Timer tick, asking Buildbucket for the build status
[08:22:08.282] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:08.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:39:0) after 1m0s
[08:23:08.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:39:0)
[08:23:08.326] Timer tick, asking Buildbucket for the build status
[08:23:08.457] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:08.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:40:0) after 1m0s
[08:24:08.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:40:0)
[08:24:08.553] Timer tick, asking Buildbucket for the build status
[08:24:08.637] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:08.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:41:0) after 1m0s
[08:25:08.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:41:0)
[08:25:08.682] Timer tick, asking Buildbucket for the build status
[08:25:08.997] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:08.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:42:0) after 1m0s
[08:26:09.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:42:0)
[08:26:09.033] Timer tick, asking Buildbucket for the build status
[08:26:10.313] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:10.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:43:0) after 1m0s
[08:27:10.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:43:0)
[08:27:10.374] Timer tick, asking Buildbucket for the build status
[08:27:10.465] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:10.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:44:0) after 1m0s
[08:28:10.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:44:0)
[08:28:10.549] Timer tick, asking Buildbucket for the build status
[08:28:10.684] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:10.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:45:0) after 1m0s
[08:29:10.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:45:0)
[08:29:10.794] Timer tick, asking Buildbucket for the build status
[08:29:10.958] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:10.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:46:0) after 1m0s
[08:30:10.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:46:0)
[08:30:10.990] Timer tick, asking Buildbucket for the build status
[08:30:11.804] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:11.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:47:0) after 1m0s
[08:31:11.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:47:0)
[08:31:11.914] Timer tick, asking Buildbucket for the build status
[08:31:12.055] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:12.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:48:0) after 1m0s
[08:32:12.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:48:0)
[08:32:12.144] Timer tick, asking Buildbucket for the build status
[08:32:12.266] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:12.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:49:0) after 1m0s
[08:33:12.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:49:0)
[08:33:12.355] Timer tick, asking Buildbucket for the build status
[08:33:12.505] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:12.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:50:0) after 1m0s
[08:34:12.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:50:0)
[08:34:12.592] Timer tick, asking Buildbucket for the build status
[08:34:12.722] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:12.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:51:0) after 1m0s
[08:35:12.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:51:0)
[08:35:12.824] Timer tick, asking Buildbucket for the build status
[08:35:13.176] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:13.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:52:0) after 1m0s
[08:36:13.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:52:0)
[08:36:13.226] Timer tick, asking Buildbucket for the build status
[08:36:13.367] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:13.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:53:0) after 1m0s
[08:37:13.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:53:0)
[08:37:13.486] Timer tick, asking Buildbucket for the build status
[08:37:13.583] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:13.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:54:0) after 1m0s
[08:38:13.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:54:0)
[08:38:13.701] Timer tick, asking Buildbucket for the build status
[08:38:13.786] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:13.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:55:0) after 1m0s
[08:39:13.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:55:0)
[08:39:13.848] Timer tick, asking Buildbucket for the build status
[08:39:13.928] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:13.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:56:0) after 1m0s
[08:40:13.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:56:0)
[08:40:13.978] Timer tick, asking Buildbucket for the build status
[08:40:14.182] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:14.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:57:0) after 1m0s
[08:41:14.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:57:0)
[08:41:14.228] Timer tick, asking Buildbucket for the build status
[08:41:14.356] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:14.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:58:0) after 1m0s
[08:42:14.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:58:0)
[08:42:14.507] Timer tick, asking Buildbucket for the build status
[08:42:14.635] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:14.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:59:0) after 1m0s
[08:43:14.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:59:0)
[08:43:14.786] Timer tick, asking Buildbucket for the build status
[08:43:15.225] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:15.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:60:0) after 1m0s
[08:44:15.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:60:0)
[08:44:15.275] Timer tick, asking Buildbucket for the build status
[08:44:15.527] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:15.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:61:0) after 1m0s
[08:45:15.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:61:0)
[08:45:15.557] Timer tick, asking Buildbucket for the build status
[08:45:15.654] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:15.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:62:0) after 1m0s
[08:46:15.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:62:0)
[08:46:15.679] Timer tick, asking Buildbucket for the build status
[08:46:15.837] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:15.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:63:0) after 1m0s
[08:47:15.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:63:0)
[08:47:15.878] Timer tick, asking Buildbucket for the build status
[08:47:16.546] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:16.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:64:0) after 1m0s
[08:48:16.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:64:0)
[08:48:16.604] Timer tick, asking Buildbucket for the build status
[08:48:16.695] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:16.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:65:0) after 1m0s
[08:49:16.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:65:0)
[08:49:16.763] Timer tick, asking Buildbucket for the build status
[08:49:16.832] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:16.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:66:0) after 1m0s
[08:50:16.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:66:0)
[08:50:16.895] Timer tick, asking Buildbucket for the build status
[08:50:16.981] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:16.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:67:0) after 1m0s
[08:51:17.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:67:0)
[08:51:17.127] Timer tick, asking Buildbucket for the build status
[08:51:17.595] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:17.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:68:0) after 1m0s
[08:52:17.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:68:0)
[08:52:17.805] Timer tick, asking Buildbucket for the build status
[08:52:17.924] Build 8863129183770945184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028559246891086592:69:0) after 1m0s
[08:52:19.779] Received PubSub notification, asking Buildbucket for the build status
[08:52:19.844] Build 8863129183770945184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:52:19.844] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605862331064880",
"created_by": "project:chromium",
"created_ts": "1605858356654733",
"id": "8863129183770945184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\"}}",
"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\": \"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15735, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"8930f18245df678abc944376372c77ba5e2a658b\", \"got_dawn_revision\": \"1a14e7e0244114fe737caf46e3a2690355aceafe\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\", \"got_revision_cp\": \"refs/heads/master@{#829576}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"f7df661be03dda8dd0b7d72f7acad6fae32c73a8\", \"swarm_hashes_refs/heads/master(at){#829576}_without_patch\": {\"absl_hardening_tests\": \"f06b8e182f91d96c374e3bd1b5a8dd24a0c457bf\", \"accessibility_unittests\": \"89ed4ce6607ced1ec92fd8397c034a2f76819345\", \"angle_unittests\": \"6c64d9069707bc4c0b03326116724a16bc1643c5\", \"app_shell_unittests\": \"0e1a02d73f2a1485ada8e71e85c02cba10d09e5c\", \"aura_unittests\": \"b357f79c462391181a0af26ab86e2e5e6e509d42\", \"base_unittests\": \"2beae7062c089cff100f9eda616ca0f7598b259e\", \"base_util_unittests\": \"d73d41d68eafcbb3f43c03c5d0342e893c08f580\", \"blink_common_unittests\": \"985c3505d58832dd9d388e73b13ba7ad1ffbe0e7\", \"blink_fuzzer_unittests\": \"3064c7aacd34701ec81d7ac1a845d3f1b148ec53\", \"blink_heap_unittests\": \"b172ffc6eecf27e662d1a1b2cac9c54623778cf4\", \"blink_platform_unittests\": \"4de941d351c9335ca1fc83410fb43ab32e96553e\", \"blink_unittests\": \"4644c0977daa6911576343e74106a4f8768acc11\", \"boringssl_crypto_tests\": \"8e4bcc4f5953c9f5b5158e19e6264b9b7cb27058\", \"boringssl_ssl_tests\": \"677a8f73ff635b8eb4519d731e438b303065d3f3\", \"browser_switcher_bho_unittests\": \"ae6de0200892e1a957cbc8f1723658c00c36c86b\", \"browser_tests\": \"9a0cbee5adcaa1b53b6f43e138299a11dca745df\", \"capture_unittests\": \"82b25bfdb0f7a52abdf829bb10b525236647d7f9\", \"cast_unittests\": \"b55fd16ff3841573fcda888a568e80f1707277af\", \"cc_unittests\": \"931e790c9128c6e1ac924a1628d6230ba6921caf\", \"chrome_app_unittests\": \"570d98f4d3ded526507bd8e8c4eb028be5aa9bd1\", \"chrome_cleaner_unittests\": \"80427aec06fbec05f8610acd377888794af53eb2\", \"chrome_elf_unittests\": \"c9e2285c5cc341c0ce3333811223530c75abfdad\", \"chromedriver_unittests\": \"d08e8386e5474e1bce36ca40d3809a1e5b3eef48\", \"components_browsertests\": \"02cad61be0483d1bfd39b8407424a2c6f9070d10\", \"components_unittests\": \"5a4d51fd3c0e5bf9f7032ecb52903bd960db8eaa\", \"compositor_unittests\": \"31627e908fb9d11c1a165d8cd8d8d8559af24a0e\", \"content_browsertests\": \"ca5402fcd7a8394e5db71da09c9e1ea8a9a14c22\", \"content_unittests\": \"550f5f390ae3ae44ea9cae5592a6cf3c3eb64381\", \"courgette_unittests\": \"fa19ef6d7ebf6ec93ad130f7913803e81b6f20fe\", \"crashpad_tests\": \"15e00eeb7ed95f8bc5bd165c521a53d1f5af3b95\", \"cronet_tests\": \"abdac313abe8848ea7b696943ad1fe537951044b\", \"cronet_unittests\": \"d748b06c0390a0d0539fb58b88349ca50fa0b895\", \"crypto_unittests\": \"938653690544de6ce0eabf6d3d0cbf246c1aee87\", \"delayloads_unittests\": \"331db8ab9937f9c8479e95cd83ee2d2075cebae7\", \"device_unittests\": \"cb3dab55a1d1454542403ad52fbc4334baabff40\", \"display_unittests\": \"5af957c381fecc7b79e8bf2f95900d12240ecf52\", \"elevation_service_unittests\": \"72d1024bd8e31c7ae1ad90940e4b7944bfef95e5\", \"events_unittests\": \"a8dc591ddaddf2a30b0633f99549d4adbede7168\", \"extensions_browsertests\": \"d430a365db33145d470e7976e5b65daceddd8daf\", \"extensions_unittests\": \"eae5caea6cac6ffef124d8d4d072a806c044f245\", \"filesystem_service_unittests\": \"eed3a7573de05ea8b31260fb398b6e3f52f8f429\", \"gcm_unit_tests\": \"49a1b077a13d5e9ba5c9835bf96c124185e68fa2\", \"gcp_unittests\": \"60c9d75fb861a9b2193f4b76cfd4018f7b782d5b\", \"gfx_unittests\": \"965f477716537057b2e6be1e96e32d88b97bbe22\", \"gin_unittests\": \"8e758b2c076f2200b7008d76337a91fff8c6d53a\", \"google_apis_unittests\": \"17337b318a1d5ac345f666c0909209fe8cbeeac4\", \"gpu_unittests\": \"15469b547382bdae9e00631ecfcd62932fb5818d\", \"gwp_asan_unittests\": \"01843b85ea0b88ad478fd17011a60db2adec67cf\", \"headless_browsertests\": \"790ec055f59735650d1fde5605a43be050e24aab\", \"headless_unittests\": \"542778f649fe38cc6586d3bac413a69cd34ccb20\", \"install_static_unittests\": \"de3d0d3c6ea6efd84556f5d66fb190f2e005882a\", \"installer_util_unittests\": \"6e4e10da329884e1bc27f96f3cfc182cd45e4baa\", \"interactive_ui_tests\": \"8efd07ec4cd4296f3c6ad47a7bb6aa31fe9b45ab\", \"ipc_tests\": \"39691bf30edb323a735d76b2f57a6a8c433d4eef\", \"jingle_unittests\": \"24bf7381dd302b34d17490a6cbee995fa2bb5dfe\", \"latency_unittests\": \"538d35e94dff0cbba8d7fd37ebf6c97334981bb2\", \"libjingle_xmpp_unittests\": \"f84d57c462a95e79756bb66484a848cb74645c94\", \"liburlpattern_unittests\": \"6e575255f6fb1ede93a071fb0f21de50fbf88a0b\", \"media_blink_unittests\": \"7ec62a6fa6d89d3c539a455e303774f06c40f5da\", \"media_unittests\": \"44e3265a996323def4f672922d612170fb75fa80\", \"message_center_unittests\": \"5f178475641d39395c4c2146a1ded51d219f3d2a\", \"midi_unittests\": \"4e4a09465b554f4d8fe397e2781c30b40c2d2f73\", \"mojo_core_unittests\": \"3701867261c5349b5e73169b8b4c5652c9cf50f3\", \"mojo_unittests\": \"0c30a72a296ac928e21b6278dabf34ab3ac6ea76\", \"nacl_loader_unittests\": \"f4c174de87678d00b4f38ee2640d45ab4c70e0cf\", \"native_theme_unittests\": \"57cd5902cff4f2984f3f4c3f7822d418f07825b5\", \"net_unittests\": \"9a5cb0c263ebb089b84090b24a0f11506a04b3a3\", \"notification_helper_unittests\": \"bfc841aab56343648c93bdbb606a6ba40f97c6c0\", \"pdf_unittests\": \"5bfb46ea49ca4bded66b9935957d5d3fc4e31fb7\", \"perfetto_unittests\": \"7387bc504a826fa5f29206c79b5e5ccd03a9f8cc\", \"ppapi_unittests\": \"3a8657293d3cf04b4da4874183036760edf5b62a\", \"printing_unittests\": \"f925e3dfd267b006a463b93d9c38d9f3a692423e\", \"remoting_unittests\": \"2fa812af3d012e632d01fc7d61a59a36e8de59ba\", \"sbox_integration_tests\": \"849204802376b773aee17daa2645adc83c4d6ccd\", \"sbox_unittests\": \"201a7c606e6e7c4c5ad33e1c2a3e2cf39bcad4a2\", \"sbox_validation_tests\": \"eaa3301c9fcaf90cfa392f43759b3107f14cf32b\", \"service_manager_unittests\": \"af09ce9d6c6ad7242375beb21e80f39a1bbe7675\", \"services_unittests\": \"cb362153507adcbb99031d1f59c58d38f76ab7b2\", \"setup_unittests\": \"2e7cc7703663dec14c699c38355401b5101184c9\", \"shell_dialogs_unittests\": \"5f566545b5860fe537f12c976f689b49c8303a14\", \"skia_unittests\": \"291d189b5d2446e6261b4df074f53cea51476ac1\", \"snapshot_unittests\": \"ea36524da9a79461c3e73996e334f45718e7c032\", \"sql_unittests\": \"c63938f50f1f58bde2e21f6766f9bf9869aa9b64\", \"storage_unittests\": \"9b76f712d33a27102a059fd838b3101447a27240\", \"sync_integration_tests\": \"9d20d0da46686456cbc9649c6b1083c1fe9ea478\", \"traffic_annotation_auditor_unittests\": \"e23d1178082c6f773ea653c67279199b78e9e4b1\", \"ui_base_unittests\": \"210240ec5da74721a02ead7205a2da948367a80e\", \"ui_touch_selection_unittests\": \"bcc465b3c0fb8e5f57e42a986970b482ddc43d4a\", \"unit_tests\": \"998655f163be858e495d118fe785342f92c09e60\", \"updater_tests\": \"923b4fe59a4f79030c1e80cc2af5d8a8dc629267\", \"url_unittests\": \"d97ae4d3dd1f2c13e544ceb546f01d8415e77050\", \"views_unittests\": \"9d763b394f4c64a2c24e3bda4133db2a7637b035\", \"viz_unittests\": \"022c7ba1d4b4a53790640c11c71ebb14e7c8ea56\", \"vr_common_unittests\": \"c5afe4adba6f659761fcd4237044d217a0ec42d9\", \"vr_pixeltests\": \"b8d5e5f6aa67e752f440448e6b856bd492f2cff0\", \"weblayer_browsertests\": \"5a3428b305d0aa36a52a112cf2400e582ee32ca8\", \"weblayer_unittests\": \"14471efc53225b88ba6f5e6968b06f4923892245\", \"wm_unittests\": \"329cb221599f3f03c6205b7214bad1bf657379a7\", \"wtf_unittests\": \"55882400c09ead01fdbce920864c552f10969a91\", \"zlib_unittests\": \"5080115b9399fe5445165daf863d6aa3d42eb15a\", \"zucchini_unittests\": \"64f0dc7b9f2e9aed2fb7eef771fba318df698d9f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xoby\"], \"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\": [\"5637-4d8b832\"], \"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": "1605858358432666",
"status": "COMPLETED",
"status_changed_ts": "1605862334291958",
"tags": [
"build_address:luci.chromium.ci/win-asan/15735",
"builder:win-asan",
"buildset:commit/git/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f7df661be03dda8dd0b7d72f7acad6fae32c73a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028559246891086592",
"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/8863129183770945184/+/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:4ffd6f78a5976910",
"user_agent:luci-scheduler"
],
"updated_ts": "1605862334695711",
"url": "https://ci.chromium.org/b/8863129183770945184",
"utcnow_ts": "1605862339836255"
}
}
[08:52:19.844] Invocation finished in 1h6m25.548800602s with status SUCCEEDED