[03:42:25.521] New invocation is queued and will start shortly
[03:42:26.568] Starting the invocation (attempt 1)
[03:42:26.601] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:42:26.601] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:42:26.601] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021326808004179456",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021326808004179456",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"gitiles_ref:refs/heads/master"
]
}
[03:42:27.153] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612755746673175",
"id": "8855896746135337728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612755747059981",
"tags": [
"build_address:luci.chromium.ci/win-asan/17231",
"builder:win-asan",
"buildset:commit/git/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021326808004179456",
"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/8855896746135337728/+/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": "1612755747060572",
"url": "https://ci.chromium.org/b/8855896746135337728",
"utcnow_ts": "1612755747146082"
}
}
[03:42:27.153] Task URL: https://ci.chromium.org/b/8855896746135337728
[03:42:27.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:2:0) after 1m0s
[03:42:29.770] Received PubSub notification, asking Buildbucket for the build status
[03:42:29.846] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:2:0)
[03:43:27.173] Timer tick, asking Buildbucket for the build status
[03:43:27.262] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:4:0) after 1m0s
[03:44:27.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:4:0)
[03:44:27.363] Timer tick, asking Buildbucket for the build status
[03:44:27.495] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:27.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:5:0) after 1m0s
[03:45:27.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:5:0)
[03:45:27.523] Timer tick, asking Buildbucket for the build status
[03:45:27.629] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:27.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:6:0) after 1m0s
[03:46:27.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:6:0)
[03:46:27.701] Timer tick, asking Buildbucket for the build status
[03:46:27.791] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:7:0) after 1m0s
[03:47:27.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:7:0)
[03:47:27.985] Timer tick, asking Buildbucket for the build status
[03:47:28.066] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:28.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:8:0) after 1m0s
[03:48:28.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:8:0)
[03:48:28.235] Timer tick, asking Buildbucket for the build status
[03:48:28.344] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:28.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:9:0) after 1m0s
[03:49:28.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:9:0)
[03:49:28.360] Timer tick, asking Buildbucket for the build status
[03:49:28.430] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:28.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:10:0) after 1m0s
[03:50:28.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:10:0)
[03:50:28.532] Timer tick, asking Buildbucket for the build status
[03:50:28.584] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:28.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:11:0) after 1m0s
[03:51:29.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:11:0)
[03:51:29.369] Timer tick, asking Buildbucket for the build status
[03:51:29.453] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:12:0) after 1m0s
[03:52:30.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:12:0)
[03:52:30.097] Timer tick, asking Buildbucket for the build status
[03:52:30.280] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:30.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:13:0) after 1m0s
[03:53:30.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:13:0)
[03:53:30.372] Timer tick, asking Buildbucket for the build status
[03:53:30.444] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:30.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:14:0) after 1m0s
[03:54:30.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:14:0)
[03:54:30.599] Timer tick, asking Buildbucket for the build status
[03:54:30.686] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:30.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:15:0) after 1m0s
[03:55:30.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:15:0)
[03:55:30.755] Timer tick, asking Buildbucket for the build status
[03:55:30.822] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:30.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:16:0) after 1m0s
[03:56:30.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:16:0)
[03:56:30.959] Timer tick, asking Buildbucket for the build status
[03:56:31.037] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:31.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:17:0) after 1m0s
[03:57:31.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:17:0)
[03:57:31.131] Timer tick, asking Buildbucket for the build status
[03:57:31.253] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:31.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:18:0) after 1m0s
[03:58:31.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:18:0)
[03:58:31.395] Timer tick, asking Buildbucket for the build status
[03:58:31.498] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:31.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:19:0) after 1m0s
[03:59:31.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:19:0)
[03:59:31.554] Timer tick, asking Buildbucket for the build status
[03:59:31.695] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:31.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:20:0) after 1m0s
[04:00:31.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:20:0)
[04:00:31.825] Timer tick, asking Buildbucket for the build status
[04:00:31.883] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:31.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:21:0) after 1m0s
[04:01:32.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:21:0)
[04:01:32.073] Timer tick, asking Buildbucket for the build status
[04:01:32.168] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:32.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:22:0) after 1m0s
[04:02:32.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:22:0)
[04:02:32.258] Timer tick, asking Buildbucket for the build status
[04:02:32.388] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:32.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:23:0) after 1m0s
[04:03:32.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:23:0)
[04:03:32.409] Timer tick, asking Buildbucket for the build status
[04:03:32.684] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:32.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:24:0) after 1m0s
[04:04:32.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:24:0)
[04:04:32.736] Timer tick, asking Buildbucket for the build status
[04:04:32.829] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:32.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:25:0) after 1m0s
[04:05:32.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:25:0)
[04:05:32.997] Timer tick, asking Buildbucket for the build status
[04:05:33.087] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:33.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:26:0) after 1m0s
[04:06:33.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:26:0)
[04:06:33.187] Timer tick, asking Buildbucket for the build status
[04:06:33.544] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:33.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:27:0) after 1m0s
[04:07:33.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:27:0)
[04:07:33.575] Timer tick, asking Buildbucket for the build status
[04:07:33.901] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:33.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:28:0) after 1m0s
[04:08:33.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:28:0)
[04:08:33.967] Timer tick, asking Buildbucket for the build status
[04:08:34.275] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:34.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:29:0) after 1m0s
[04:09:34.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:29:0)
[04:09:34.256] Timer tick, asking Buildbucket for the build status
[04:09:34.463] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:34.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:30:0) after 1m0s
[04:10:34.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:30:0)
[04:10:34.473] Timer tick, asking Buildbucket for the build status
[04:10:34.592] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:34.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:31:0) after 1m0s
[04:11:34.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:31:0)
[04:11:34.647] Timer tick, asking Buildbucket for the build status
[04:11:35.624] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:35.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:32:0) after 1m0s
[04:12:35.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:32:0)
[04:12:35.682] Timer tick, asking Buildbucket for the build status
[04:12:35.799] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:35.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:33:0) after 1m0s
[04:13:35.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:33:0)
[04:13:35.936] Timer tick, asking Buildbucket for the build status
[04:13:36.326] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:36.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:34:0) after 1m0s
[04:14:36.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:34:0)
[04:14:36.311] Timer tick, asking Buildbucket for the build status
[04:14:36.434] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:36.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:35:0) after 1m0s
[04:15:36.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:35:0)
[04:15:36.452] Timer tick, asking Buildbucket for the build status
[04:15:36.829] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:36.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:36:0) after 1m0s
[04:16:36.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:36:0)
[04:16:36.896] Timer tick, asking Buildbucket for the build status
[04:16:37.885] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:37.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:37:0) after 1m0s
[04:17:37.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:37:0)
[04:17:37.942] Timer tick, asking Buildbucket for the build status
[04:17:38.462] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:38.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:38:0) after 1m0s
[04:18:38.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:38:0)
[04:18:38.498] Timer tick, asking Buildbucket for the build status
[04:18:38.949] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:38.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:39:0) after 1m0s
[04:19:39.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:39:0)
[04:19:39.036] Timer tick, asking Buildbucket for the build status
[04:19:39.336] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:40:0) after 1m0s
[04:20:39.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:40:0)
[04:20:39.395] Timer tick, asking Buildbucket for the build status
[04:20:39.518] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:39.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:41:0) after 1m0s
[04:21:39.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:41:0)
[04:21:39.566] Timer tick, asking Buildbucket for the build status
[04:21:39.637] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:39.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:42:0) after 1m0s
[04:22:39.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:42:0)
[04:22:39.658] Timer tick, asking Buildbucket for the build status
[04:22:39.807] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:39.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:43:0) after 1m0s
[04:23:39.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:43:0)
[04:23:39.921] Timer tick, asking Buildbucket for the build status
[04:23:39.998] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:39.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:44:0) after 1m0s
[04:24:39.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:44:0)
[04:24:39.983] Timer tick, asking Buildbucket for the build status
[04:24:40.091] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:40.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:45:0) after 1m0s
[04:25:40.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:45:0)
[04:25:40.191] Timer tick, asking Buildbucket for the build status
[04:25:40.262] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:40.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:46:0) after 1m0s
[04:26:40.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:46:0)
[04:26:40.285] Timer tick, asking Buildbucket for the build status
[04:26:40.446] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:40.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:47:0) after 1m0s
[04:27:40.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:47:0)
[04:27:40.461] Timer tick, asking Buildbucket for the build status
[04:27:40.590] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:40.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:48:0) after 1m0s
[04:28:40.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:48:0)
[04:28:40.610] Timer tick, asking Buildbucket for the build status
[04:28:40.786] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:40.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:49:0) after 1m0s
[04:29:40.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:49:0)
[04:29:40.851] Timer tick, asking Buildbucket for the build status
[04:29:40.984] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:40.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:50:0) after 1m0s
[04:30:41.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:50:0)
[04:30:41.067] Timer tick, asking Buildbucket for the build status
[04:30:41.206] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:51:0) after 1m0s
[04:31:41.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:51:0)
[04:31:41.259] Timer tick, asking Buildbucket for the build status
[04:31:41.844] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:41.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:52:0) after 1m0s
[04:32:41.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:52:0)
[04:32:41.934] Timer tick, asking Buildbucket for the build status
[04:32:42.062] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:53:0) after 1m0s
[04:33:42.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:53:0)
[04:33:42.135] Timer tick, asking Buildbucket for the build status
[04:33:42.268] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:42.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:54:0) after 1m0s
[04:34:42.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:54:0)
[04:34:42.254] Timer tick, asking Buildbucket for the build status
[04:34:42.320] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:42.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:55:0) after 1m0s
[04:35:42.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:55:0)
[04:35:42.307] Timer tick, asking Buildbucket for the build status
[04:35:42.516] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:42.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:56:0) after 1m0s
[04:36:42.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:56:0)
[04:36:42.536] Timer tick, asking Buildbucket for the build status
[04:36:42.687] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:42.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:57:0) after 1m0s
[04:37:42.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:57:0)
[04:37:42.800] Timer tick, asking Buildbucket for the build status
[04:37:42.911] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:42.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:58:0) after 1m0s
[04:38:42.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:58:0)
[04:38:42.971] Timer tick, asking Buildbucket for the build status
[04:38:43.086] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:43.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:59:0) after 1m0s
[04:39:43.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:59:0)
[04:39:43.115] Timer tick, asking Buildbucket for the build status
[04:39:43.217] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:43.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:60:0) after 1m0s
[04:40:43.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:60:0)
[04:40:43.236] Timer tick, asking Buildbucket for the build status
[04:40:43.399] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:43.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:61:0) after 1m0s
[04:41:43.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:61:0)
[04:41:43.486] Timer tick, asking Buildbucket for the build status
[04:41:43.557] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:43.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:62:0) after 1m0s
[04:42:43.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:62:0)
[04:42:43.621] Timer tick, asking Buildbucket for the build status
[04:42:43.744] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:43.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:63:0) after 1m0s
[04:43:43.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:63:0)
[04:43:43.764] Timer tick, asking Buildbucket for the build status
[04:43:43.883] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:43.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:64:0) after 1m0s
[04:44:45.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:64:0)
[04:44:45.358] Timer tick, asking Buildbucket for the build status
[04:44:45.529] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:45.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:65:0) after 1m0s
[04:45:45.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:65:0)
[04:45:45.616] Timer tick, asking Buildbucket for the build status
[04:45:45.712] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:45.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:66:0) after 1m0s
[04:46:45.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:66:0)
[04:46:45.748] Timer tick, asking Buildbucket for the build status
[04:46:45.883] Build 8855896746135337728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:45.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021326808004179456:67:0) after 1m0s
[04:47:34.065] Received PubSub notification, asking Buildbucket for the build status
[04:47:34.192] Build 8855896746135337728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:47:34.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612759652356580",
"created_by": "project:chromium",
"created_ts": "1612755746673175",
"id": "8855896746135337728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\"}}",
"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\": \"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17231, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"8dae4007a491f1cbba5eca3706011c4d10199f6d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\", \"got_revision_cp\": \"refs/heads/master@{#851583}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"c3c63c8ceaf4ac3646b1e17bfe8a97e7102f0913\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33185}\", \"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\": \"6595e5c28dbe88644495aa2c90f8a9f88f41c00f\", \"swarm_hashes_refs/heads/master(at){#851583}_without_patch\": {\"absl_hardening_tests\": \"e923a172c088f98e05d18ec38e1f1a09870cd82b\", \"accessibility_unittests\": \"e836bf04bdec11fc76811639e4319ff74adfd8fd\", \"angle_unittests\": \"aee15598deca07f2daa6772d65c6a106548aefed\", \"app_shell_unittests\": \"24d032e02f39e9d234b8084f702296b771d9c599\", \"aura_unittests\": \"94679258df7c285c1a934ee4b815441b06ff5aab\", \"base_unittests\": \"d312f9960a5281bcf544775b70cd6058c9c0a662\", \"base_util_unittests\": \"cac3139eb4c7310596545a872b20b3535465153c\", \"blink_common_unittests\": \"c449be91564fa6eda09688be287260198bfd169c\", \"blink_fuzzer_unittests\": \"5bcfc7daf8583bf61dd60ea06a1d053ab39155ae\", \"blink_heap_unittests\": \"eeb08f133a012761a8a8af23f0e1015060b0dda3\", \"blink_platform_unittests\": \"2e4443bf2fe8edecbc663a56f01d2f93fdf62955\", \"blink_unittests\": \"208ed6695472a3192f57e4c13490e2a60dc26812\", \"boringssl_crypto_tests\": \"2ce249d500b147cb8b3383d194860d56c98980e5\", \"boringssl_ssl_tests\": \"285463a5fbab12512d318753623a9364b24cc624\", \"browser_switcher_bho_unittests\": \"b0e86d55a229bc47f2f00fdc763151c979864031\", \"browser_tests\": \"b9d330ef7ce56b9e0401d8c80a71d6275cc41df4\", \"capture_unittests\": \"9943c9a99b1b524f831b41ff103b544105f622cf\", \"cast_unittests\": \"8412d1cba6d2ce9fc2afae781071d290da35d0e1\", \"cc_unittests\": \"602030dc5179a0c09ad0447d69c2227516540ad6\", \"chrome_app_unittests\": \"e8f818d50a6db49775ca5baadedd4bf21fe20990\", \"chrome_cleaner_unittests\": \"c6185b19c7f316d2c6d809e9cee6a2000a8af41a\", \"chrome_elf_unittests\": \"25936deeb6482837d59bae4fc4a17fece417d4cb\", \"chromedriver_unittests\": \"d66fe27f5aa490b1d0c683fd5c5d93076ad14160\", \"components_browsertests\": \"5f48a016c921b9a903d0569d38f695e79d525728\", \"components_unittests\": \"bfa29d90b6329a0960bd8c5ee4c0027fee344eab\", \"compositor_unittests\": \"19a2ccd0a6a4d16c75cdb983ec3dd46f52e3240a\", \"content_browsertests\": \"177e182796127f7cd6e726abbc1ebfe8e7473a94\", \"content_unittests\": \"af5458819531c004aa08f936f9eff087fec96df6\", \"courgette_unittests\": \"227317c338ac853b0db91a6712b18156ca99beef\", \"crashpad_tests\": \"659ce6a3aa3f7bd13949413748e068bee8f094a7\", \"cronet_tests\": \"f76190e9ae2f148dbab3ecc345e7b488874eb3a9\", \"cronet_unittests\": \"bf143fd63b7ae58d55b6613ab11fba2de864c141\", \"crypto_unittests\": \"019ce5214aeac7b9ac33888c8e1feff043d7a407\", \"delayloads_unittests\": \"eacd30c7693c8547890120de7f63a8a3f0556feb\", \"device_unittests\": \"4a267f5000a98d50e794bfc68455cfca11c2150c\", \"display_unittests\": \"c856634c238cf2cb4e6b16f0056489c584a97295\", \"elevation_service_unittests\": \"15806a670b858c98f53a5b5f2ee937c50f9145f9\", \"events_unittests\": \"6d99946feb80f44b21f7c2d0783144d74815d008\", \"extensions_browsertests\": \"32bba70e16f1441ae49adadc67302b9c844c6e7c\", \"extensions_unittests\": \"95269c4b518d7ecad7019546cf3f21a7fb335ab2\", \"filesystem_service_unittests\": \"5eb6a8b40c41405801e8b12388643ad2ff6a90e5\", \"gcm_unit_tests\": \"ef0779dec423a3fc582b23fb0c3386d4893e2f26\", \"gcp_unittests\": \"f6821ccb6b535839d010612eec11d81b0c257624\", \"gfx_unittests\": \"de30b3e714dcd11532b8812253602fb691fe495c\", \"gin_unittests\": \"3f1f57455883b9d84012ff738be54a8e942e5050\", \"google_apis_unittests\": \"ea843cc37e897bcb2392be909bfc7627f46d30bd\", \"gpu_unittests\": \"67be13e60b171ab9e7d72ad7fb1048d6c247cfa2\", \"gwp_asan_unittests\": \"c3e390a489c6b38809020c26229665d2f4d1e4b6\", \"headless_browsertests\": \"d3a935023cc36a044368d0a4da6899c0895205a7\", \"headless_unittests\": \"4d277d4f34f4cb4ea41fffa6d57bccea7b280830\", \"install_static_unittests\": \"3885baf17c0c89985d6706843feb1c123112b09b\", \"installer_util_unittests\": \"5f790f2b80415327a65c41913da3ac8d9dce7124\", \"interactive_ui_tests\": \"7ad250d512335418fa0ad7e2fd430730b9a8bba6\", \"ipc_tests\": \"ff911ba348d51d98f36027ac4ad16c82a8a983cf\", \"jingle_unittests\": \"3a3404fb78c1896ad4e60edb7f310119f66e2f71\", \"latency_unittests\": \"8f8aca0472c6fdc48588ec96892bcbd664b4fdd1\", \"libjingle_xmpp_unittests\": \"e5a88047c4a5809b812962c6d79bcbc7bb9a0e1b\", \"liburlpattern_unittests\": \"1e38d5b7f267e3ca5b11688cd8ab3152c7d9bee0\", \"media_blink_unittests\": \"70e63e8415bd3658b0793c570b7e6ee075f99ec8\", \"media_unittests\": \"4ce8f03e47d6c5ef855275776415de6d9b2f23f0\", \"message_center_unittests\": \"c119e4076fcd045c226d3f1890c9dbfca55f44e3\", \"midi_unittests\": \"f4f7cea006f123aefb2af3a54673a17c197b9273\", \"mojo_core_unittests\": \"61a9f0b3426be0e1622a2433fa95a2be2b3f5c49\", \"mojo_unittests\": \"70ceab129a075b71e3f4f3b709c4167d42c473d6\", \"nacl_loader_unittests\": \"874e07c59e4a1b69804c4b6162fc0fb6eb0f04f7\", \"native_theme_unittests\": \"12ea67b9ba5b38821435430f23e18167718ef17e\", \"net_unittests\": \"0f82ebd3fd034069e00b0cf8d20127e659dd3c0a\", \"notification_helper_unittests\": \"03d85618937fc683005f6cd2d6b797b40c06acf5\", \"pdf_unittests\": \"6ec7573ead6e3eb1f811059503f9d661b013d00d\", \"perfetto_unittests\": \"a006052ed55b90510625c6629b5c6c6eebf448ff\", \"ppapi_unittests\": \"0c7485fc5fcff1b274c795eaa19ff6a801d7a654\", \"printing_unittests\": \"a756f48190f3c2d9b9666dcad5f7b60205b881fc\", \"remoting_unittests\": \"b84a3e7f87f317c7f635ef3c31e7fdf0371fb523\", \"sbox_integration_tests\": \"7834297f0b2125a54210a55c9f289e85925a8f01\", \"sbox_unittests\": \"c3561d031d353db6e4aba1992cb3d5f48902aa64\", \"sbox_validation_tests\": \"9e1cc4ffee760e39ebd3273281939f4c71336314\", \"service_manager_unittests\": \"35bdcdf7df4d25bf916ce0e05c610ab12a5c37e0\", \"services_unittests\": \"ed723c4af4503792a9db0693518568653406b8fb\", \"setup_unittests\": \"58231176641e462e2e2f5782e76634885dfe9f53\", \"shell_dialogs_unittests\": \"3e0df8f461a996ddf71336caf606920bd2e098be\", \"skia_unittests\": \"59c827e281c639ccac26a6aae1c8062d454a528b\", \"snapshot_unittests\": \"530661baad014ab084c1f46a8c9f50ad08d19ebf\", \"sql_unittests\": \"79bf7f461d34af2644e12f3f91df634c6153adc1\", \"storage_unittests\": \"4102b79cfb9a26040af3195f2d503a839b105835\", \"sync_integration_tests\": \"f31fcab6e833dd3c9c6814bc4c4654cf731abfb2\", \"traffic_annotation_auditor_unittests\": \"0d8e96dca4d410c5e45f6cdec03e8d21564d0393\", \"ui_base_unittests\": \"840dab7eaa5e61e8ae23f757c49897569e129c47\", \"ui_touch_selection_unittests\": \"ab2e4e04d7a27ef12a0a29a96f1b8fbc5e7fd04c\", \"unit_tests\": \"13003e524f48c1afbc2d48c00c491bc8a706d235\", \"updater_tests\": \"8c398bcfe39bb0e4266d7f11b6f0442947904d36\", \"url_unittests\": \"a52a481c06b7f5322ad7bca2fc7d7ff6d15e1897\", \"views_unittests\": \"35546443d5e2698f6cd07a53d2c9342dd3ba8f83\", \"viz_unittests\": \"d72294dcfe968470feb2a9ab6dd6d988ed4996f1\", \"vr_common_unittests\": \"3c359ee45884a37e45d6dbd978a1daf0a4ef4581\", \"vr_pixeltests\": \"28283817751cb6c064d35236988c15c12a71e319\", \"weblayer_browsertests\": \"e0c03a97549ab80d4732ac79a71629f195393128\", \"weblayer_unittests\": \"625a65c8ef2f72f14ba905ce0170d87298c0828e\", \"wm_unittests\": \"b252ab01c9096323c957a770d68ba37e9a14c019\", \"wtf_unittests\": \"573050d402c2501b94e58c0d76cad4ac13a0f03d\", \"zlib_unittests\": \"900318e66f2280297884e1cf9aebe09bf35a6c96\", \"zucchini_unittests\": \"d2c53134b45b4fe241590a278f97769626ed653f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-7-1hzs\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5681-01e7fd1\"], \"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": "1612755749202184",
"status": "COMPLETED",
"status_changed_ts": "1612759652683022",
"tags": [
"build_address:luci.chromium.ci/win-asan/17231",
"builder:win-asan",
"buildset:commit/git/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6595e5c28dbe88644495aa2c90f8a9f88f41c00f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021326808004179456",
"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/8855896746135337728/+/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:51988d49ddcba210",
"user_agent:luci-scheduler"
],
"updated_ts": "1612759652689125",
"url": "https://ci.chromium.org/b/8855896746135337728",
"utcnow_ts": "1612759654176874"
}
}
[04:47:34.192] Invocation finished in 1h5m8.685158469s with status SUCCEEDED