[11:02:24.424] New invocation is queued and will start shortly
[11:02:25.523] Starting the invocation (attempt 1)
[11:02:25.571] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:02:25.572] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:02:25.572] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023564050909216096",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c808eb818c7db79963cba1b1543fb0c891f5d194\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023564050909216096",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c808eb818c7db79963cba1b1543fb0c891f5d194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c808eb818c7db79963cba1b1543fb0c891f5d194",
"gitiles_ref:refs/heads/master"
]
}
[11:02:26.234] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610622145677129",
"id": "8858133988932613408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c808eb818c7db79963cba1b1543fb0c891f5d194\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610622146106510",
"tags": [
"build_address:luci.chromium.ci/win-asan/16814",
"builder:win-asan",
"buildset:commit/git/c808eb818c7db79963cba1b1543fb0c891f5d194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c808eb818c7db79963cba1b1543fb0c891f5d194",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023564050909216096",
"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/8858133988932613408/+/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": "1610622146106647",
"url": "https://ci.chromium.org/b/8858133988932613408",
"utcnow_ts": "1610622146226010"
}
}
[11:02:26.234] Task URL: https://ci.chromium.org/b/8858133988932613408
[11:02:26.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:2:0) after 1m0s
[11:02:29.537] Received PubSub notification, asking Buildbucket for the build status
[11:02:29.619] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:26.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:2:0)
[11:03:26.308] Timer tick, asking Buildbucket for the build status
[11:03:26.598] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:26.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:4:0) after 1m0s
[11:04:26.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:4:0)
[11:04:26.683] Timer tick, asking Buildbucket for the build status
[11:04:26.781] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:26.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:5:0) after 1m0s
[11:05:26.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:5:0)
[11:05:26.850] Timer tick, asking Buildbucket for the build status
[11:05:27.000] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:27.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:6:0) after 1m0s
[11:06:27.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:6:0)
[11:06:27.027] Timer tick, asking Buildbucket for the build status
[11:06:27.463] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:27.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:7:0) after 1m0s
[11:07:27.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:7:0)
[11:07:27.516] Timer tick, asking Buildbucket for the build status
[11:07:27.649] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:27.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:8:0) after 1m0s
[11:08:27.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:8:0)
[11:08:27.745] Timer tick, asking Buildbucket for the build status
[11:08:27.814] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:27.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:9:0) after 1m0s
[11:09:27.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:9:0)
[11:09:27.885] Timer tick, asking Buildbucket for the build status
[11:09:28.012] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:28.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:10:0) after 1m0s
[11:10:28.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:10:0)
[11:10:28.143] Timer tick, asking Buildbucket for the build status
[11:10:28.418] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:28.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:11:0) after 1m0s
[11:11:28.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:11:0)
[11:11:28.528] Timer tick, asking Buildbucket for the build status
[11:11:28.592] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:28.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:12:0) after 1m0s
[11:12:28.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:12:0)
[11:12:28.636] Timer tick, asking Buildbucket for the build status
[11:12:29.872] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:29.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:13:0) after 1m0s
[11:13:29.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:13:0)
[11:13:29.915] Timer tick, asking Buildbucket for the build status
[11:13:29.968] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:29.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:14:0) after 1m0s
[11:14:30.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:14:0)
[11:14:30.038] Timer tick, asking Buildbucket for the build status
[11:14:30.259] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:30.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:15:0) after 1m0s
[11:15:30.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:15:0)
[11:15:30.396] Timer tick, asking Buildbucket for the build status
[11:15:30.472] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:30.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:16:0) after 1m0s
[11:16:30.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:16:0)
[11:16:30.493] Timer tick, asking Buildbucket for the build status
[11:16:30.578] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:30.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:17:0) after 1m0s
[11:17:30.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:17:0)
[11:17:30.623] Timer tick, asking Buildbucket for the build status
[11:17:31.192] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:31.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:18:0) after 1m0s
[11:18:31.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:18:0)
[11:18:31.240] Timer tick, asking Buildbucket for the build status
[11:18:31.424] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:31.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:19:0) after 1m0s
[11:19:31.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:19:0)
[11:19:31.469] Timer tick, asking Buildbucket for the build status
[11:19:31.545] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:31.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:20:0) after 1m0s
[11:20:31.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:20:0)
[11:20:31.614] Timer tick, asking Buildbucket for the build status
[11:20:32.757] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:32.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:21:0) after 1m0s
[11:21:33.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:21:0)
[11:21:33.016] Timer tick, asking Buildbucket for the build status
[11:21:33.067] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:33.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:22:0) after 1m0s
[11:22:33.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:22:0)
[11:22:33.057] Timer tick, asking Buildbucket for the build status
[11:22:33.157] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:33.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:23:0) after 1m0s
[11:23:33.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:23:0)
[11:23:33.181] Timer tick, asking Buildbucket for the build status
[11:23:33.296] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:33.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:24:0) after 1m0s
[11:24:33.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:24:0)
[11:24:33.316] Timer tick, asking Buildbucket for the build status
[11:24:33.460] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:25:0) after 1m0s
[11:25:33.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:25:0)
[11:25:33.479] Timer tick, asking Buildbucket for the build status
[11:25:33.530] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:33.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:26:0) after 1m0s
[11:26:33.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:26:0)
[11:26:33.556] Timer tick, asking Buildbucket for the build status
[11:26:34.218] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:34.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:27:0) after 1m0s
[11:27:34.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:27:0)
[11:27:34.211] Timer tick, asking Buildbucket for the build status
[11:27:34.327] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:34.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:28:0) after 1m0s
[11:28:34.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:28:0)
[11:28:34.355] Timer tick, asking Buildbucket for the build status
[11:28:34.709] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:34.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:29:0) after 1m0s
[11:29:34.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:29:0)
[11:29:34.742] Timer tick, asking Buildbucket for the build status
[11:29:34.856] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:34.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:30:0) after 1m0s
[11:30:34.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:30:0)
[11:30:34.852] Timer tick, asking Buildbucket for the build status
[11:30:35.166] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:35.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:31:0) after 1m0s
[11:31:35.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:31:0)
[11:31:35.257] Timer tick, asking Buildbucket for the build status
[11:31:35.362] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:35.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:32:0) after 1m0s
[11:32:35.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:32:0)
[11:32:35.383] Timer tick, asking Buildbucket for the build status
[11:32:35.954] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:35.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:33:0) after 1m0s
[11:33:35.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:33:0)
[11:33:35.974] Timer tick, asking Buildbucket for the build status
[11:33:36.202] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:36.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:34:0) after 1m0s
[11:34:36.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:34:0)
[11:34:36.224] Timer tick, asking Buildbucket for the build status
[11:34:36.382] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:35:0) after 1m0s
[11:35:36.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:35:0)
[11:35:36.426] Timer tick, asking Buildbucket for the build status
[11:35:36.556] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:36.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:36:0) after 1m0s
[11:36:36.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:36:0)
[11:36:36.665] Timer tick, asking Buildbucket for the build status
[11:36:36.791] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:36.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:37:0) after 1m0s
[11:37:36.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:37:0)
[11:37:36.880] Timer tick, asking Buildbucket for the build status
[11:37:37.002] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:37.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:38:0) after 1m0s
[11:38:37.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:38:0)
[11:38:37.102] Timer tick, asking Buildbucket for the build status
[11:38:37.627] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:37.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:39:0) after 1m0s
[11:39:37.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:39:0)
[11:39:37.776] Timer tick, asking Buildbucket for the build status
[11:39:37.857] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:37.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:40:0) after 1m0s
[11:40:37.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:40:0)
[11:40:37.955] Timer tick, asking Buildbucket for the build status
[11:40:38.082] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:38.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:41:0) after 1m0s
[11:41:38.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:41:0)
[11:41:38.168] Timer tick, asking Buildbucket for the build status
[11:41:38.280] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:38.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:42:0) after 1m0s
[11:42:38.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:42:0)
[11:42:38.337] Timer tick, asking Buildbucket for the build status
[11:42:38.777] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:38.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:43:0) after 1m0s
[11:43:38.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:43:0)
[11:43:38.831] Timer tick, asking Buildbucket for the build status
[11:43:38.968] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:38.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:44:0) after 1m0s
[11:44:39.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:44:0)
[11:44:39.010] Timer tick, asking Buildbucket for the build status
[11:44:39.101] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:39.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:45:0) after 1m0s
[11:45:39.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:45:0)
[11:45:39.125] Timer tick, asking Buildbucket for the build status
[11:45:39.207] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:39.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:46:0) after 1m0s
[11:46:39.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:46:0)
[11:46:39.261] Timer tick, asking Buildbucket for the build status
[11:46:39.342] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:39.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:47:0) after 1m0s
[11:47:39.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:47:0)
[11:47:39.370] Timer tick, asking Buildbucket for the build status
[11:47:39.483] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:39.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:48:0) after 1m0s
[11:48:39.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:48:0)
[11:48:39.518] Timer tick, asking Buildbucket for the build status
[11:48:39.927] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:39.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:49:0) after 1m0s
[11:49:39.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:49:0)
[11:49:39.916] Timer tick, asking Buildbucket for the build status
[11:49:40.048] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:40.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:50:0) after 1m0s
[11:50:40.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:50:0)
[11:50:40.117] Timer tick, asking Buildbucket for the build status
[11:50:40.318] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:40.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:51:0) after 1m0s
[11:51:40.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:51:0)
[11:51:40.405] Timer tick, asking Buildbucket for the build status
[11:51:40.564] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:40.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:52:0) after 1m0s
[11:52:40.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:52:0)
[11:52:40.626] Timer tick, asking Buildbucket for the build status
[11:52:40.848] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:40.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:53:0) after 1m0s
[11:53:40.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:53:0)
[11:53:40.840] Timer tick, asking Buildbucket for the build status
[11:53:41.196] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:54:0) after 1m0s
[11:54:41.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:54:0)
[11:54:41.248] Timer tick, asking Buildbucket for the build status
[11:54:41.413] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:41.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:55:0) after 1m0s
[11:55:41.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:55:0)
[11:55:41.447] Timer tick, asking Buildbucket for the build status
[11:55:41.747] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:41.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:56:0) after 1m0s
[11:56:42.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:56:0)
[11:56:42.285] Timer tick, asking Buildbucket for the build status
[11:56:42.497] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:42.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:57:0) after 1m0s
[11:57:42.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:57:0)
[11:57:42.625] Timer tick, asking Buildbucket for the build status
[11:57:42.951] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:42.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:58:0) after 1m0s
[11:58:42.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:58:0)
[11:58:42.951] Timer tick, asking Buildbucket for the build status
[11:58:43.132] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:43.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:59:0) after 1m0s
[11:59:43.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:59:0)
[11:59:43.146] Timer tick, asking Buildbucket for the build status
[11:59:43.406] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:43.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:60:0) after 1m0s
[12:00:43.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:60:0)
[12:00:43.467] Timer tick, asking Buildbucket for the build status
[12:00:43.925] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:61:0) after 1m0s
[12:01:43.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:61:0)
[12:01:43.962] Timer tick, asking Buildbucket for the build status
[12:01:44.066] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:44.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:62:0) after 1m0s
[12:02:44.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:62:0)
[12:02:44.071] Timer tick, asking Buildbucket for the build status
[12:02:44.219] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:44.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:63:0) after 1m0s
[12:03:44.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:63:0)
[12:03:44.195] Timer tick, asking Buildbucket for the build status
[12:03:44.390] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:44.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:64:0) after 1m0s
[12:04:44.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:64:0)
[12:04:44.422] Timer tick, asking Buildbucket for the build status
[12:04:44.926] Build 8858133988932613408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:44.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023564050909216096:65:0) after 1m0s
[12:05:30.572] Received PubSub notification, asking Buildbucket for the build status
[12:05:30.706] Build 8858133988932613408: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:05:30.706] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610625929675588",
"created_by": "project:chromium",
"created_ts": "1610622145677129",
"id": "8858133988932613408",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c808eb818c7db79963cba1b1543fb0c891f5d194\"}}",
"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\": \"c808eb818c7db79963cba1b1543fb0c891f5d194\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16814, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"198dc345383e1f17252945bdb4a6dd47f4345ad4\", \"got_dawn_revision\": \"6f833b7f78c92595d3ec74ee622dab1868fdf40b\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"c808eb818c7db79963cba1b1543fb0c891f5d194\", \"got_revision_cp\": \"refs/heads/master@{#843478}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"c80a50a3c4de747d61e044cea6f94223016110d2\", \"got_v8_revision_cp\": \"refs/heads/8.9.273@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"c808eb818c7db79963cba1b1543fb0c891f5d194\", \"swarm_hashes_refs/heads/master(at){#843478}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"bb60612bbaee673c1379f800a28e565e0dec638d\", \"angle_unittests\": \"dd3bee314122ee6d324b179bfa33bf78e3b7f0b1\", \"app_shell_unittests\": \"7f7cfeb50e649b7db9944e6b71f8f3e9415b20e9\", \"aura_unittests\": \"4da0f42541785edf19c704e7a57bf778aa1bd547\", \"base_unittests\": \"d7d5c005ec2ce9bfde608131f981b41d74c7c49c\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"b345c31aa6602ecf46b59eba5c687422d2b35f19\", \"blink_fuzzer_unittests\": \"0a9b691e831017ab86b64be1e8234288f72e497e\", \"blink_heap_unittests\": \"7369a1517210cd8398186bb7f6c61f0deea23fd3\", \"blink_platform_unittests\": \"34514f95ba01c0e42fe29cd9805d03b7be2612cf\", \"blink_unittests\": \"3da273ee567805cf4526df24fa277d3f82fe85e1\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"17631431598cf8ce1a7e0c1e75050fa67e1017d7\", \"capture_unittests\": \"2b05bf9075ef0c21a25fa0ed44cec2a9b30ca39b\", \"cast_unittests\": \"220cc143c55717fbb2e7e9ac9fe047e0dca3d471\", \"cc_unittests\": \"98ae9594102c5e994a875d29941c22960b455491\", \"chrome_app_unittests\": \"06df4cc323a815772902a5d0782fa1daba97c457\", \"chrome_cleaner_unittests\": \"ebb65a3c205088022c8ea274168ca687dd68352a\", \"chrome_elf_unittests\": \"a516b7fc3e94ae9f532657dc03235848b409d237\", \"chromedriver_unittests\": \"64e6bb981252cc90f5f701115e12c9be99125e10\", \"components_browsertests\": \"30d7301e2581ade26405723153bed5387bdf65d4\", \"components_unittests\": \"43e9a4f794cc9a1228f5bab2089b6f8be5ddc6b0\", \"compositor_unittests\": \"eec56a303cc54cc8b8f94e0fa617f13a15ad82ba\", \"content_browsertests\": \"a0433dab658302d28dfcdce334c486954362eb90\", \"content_unittests\": \"c41be70c7af0fb13beaec0cbb018d550aca3ccf7\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"440ad65c2a7566a8b0679d7a1ca0d41a17d6f0c6\", \"device_unittests\": \"46cb4c635c5ac10d6f928cb544065a45601cf066\", \"display_unittests\": \"0b16569f1432aaa0fa4665ff271d1e39439dc44f\", \"elevation_service_unittests\": \"578cacb4fe92c983702a0790f26fc4104c61a0b1\", \"events_unittests\": \"8a6c6778347bd7b6c2d68509f7e960cba4b2c9c3\", \"extensions_browsertests\": \"a13f8cdbd8444c49cfddfe0b7d22c6439a41f771\", \"extensions_unittests\": \"a8a8d0b28f9f8cf30272b8b414022a4741066029\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"542708573e98fe59544681bdabaeeae4b12fb47e\", \"gcp_unittests\": \"9f29ee118df11e1615caac731e208f0f70dbb41a\", \"gfx_unittests\": \"3344ec1118da5b600ffa5995a201d85513ca719b\", \"gin_unittests\": \"db00921617e1fd9e2ec4a2b8cca1d56f3c9cdb2b\", \"google_apis_unittests\": \"e7d02536f42b85b87b0c7a40be20abdb175c17ab\", \"gpu_unittests\": \"627ab251872b20c01718312106cc866ff30a4dac\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"ffdff5c2b711e0dfffd3686b0093cbaef29e3269\", \"headless_unittests\": \"7ea28826ab1d42b4ed72c32d560c390c57b3ce21\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"7875e4cf6f8354b49b364f38924fbeac62ffb936\", \"interactive_ui_tests\": \"139f8fbb12f82ab4c8e075ca97bc294362875901\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"5d15934670ec8d146d05a79f40944b56dba1cc06\", \"latency_unittests\": \"1b765adf3581700311a0913d4c0ebdd9489f1a9c\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"415887165469fa9bf34dbd206cae950dafc48870\", \"media_unittests\": \"6b30bb88318428af4dff8b61e6f099c1a9d18782\", \"message_center_unittests\": \"a2bd8a9663af24a655cd1a1ca80c81d95a324c58\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"5cca590ccf1907633df4459eff45e2aff8b351a7\", \"nacl_loader_unittests\": \"ce0fbe602c0380709dfe5629421fb66be788efca\", \"native_theme_unittests\": \"592a7736a8bd0345c62a28545506ddaac1ae1aa0\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"b579b298735a4e9335e0c1e4e520b9662c86121f\", \"pdf_unittests\": \"a82969c9e5c0ccb3b16f2563febecfc423e352f9\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"0a1bab8394c46efb2f46bd6547197ef3446a46b1\", \"printing_unittests\": \"f97c06dab20d862968845f35698a2871fbd60e9c\", \"remoting_unittests\": \"455d0a35996de8f9841cc40ab229ee602c4f0032\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"a9e7eddc801fe9c0b2981b51bc9ed45ddf879bad\", \"services_unittests\": \"8d09b4b88c842b4129bdeea68ee2b129e1c71090\", \"setup_unittests\": \"1f875fcbc3919b3df856cbc0c8f76a65302f4d20\", \"shell_dialogs_unittests\": \"a5e3fbeff21da3a5c0fa414c786cde93a38e69bd\", \"skia_unittests\": \"9db21c2730a41032242ca7ac5468ab8993e5862e\", \"snapshot_unittests\": \"a549120b4539800e346cd045da59e31538fb3fa0\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"9c894ef44a8e25218d96afbd071c491c954ccde2\", \"sync_integration_tests\": \"3cac47641b5b1c647ed1e9751a37162f0c40d318\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"9743e5a2a774ed211fa62b7b75d37c46da6cb9ef\", \"ui_touch_selection_unittests\": \"3a715f161fd28dd622b5bcef63e527b59404de4d\", \"unit_tests\": \"2261b1787896828617f7f863325912d9e6d4b418\", \"updater_tests\": \"e82a7565c75a2c92f657b818c3e6976b4a591660\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"6a0e0325720eeb2f285c59b0ef60076733e198f9\", \"viz_unittests\": \"4af3a0c79ffc60da7ee55b5e2a9c17e3b54e6ae7\", \"vr_common_unittests\": \"69ce90c9dd54832e3539854fd68365af191e35e9\", \"vr_pixeltests\": \"526bccf8dc9f162c5d0edcaad5b1102f6e462caf\", \"weblayer_browsertests\": \"7e3c249d51b09ea404db684930258e19627b558a\", \"weblayer_unittests\": \"04315908b6a7256f61635ba5541f0e9d343350af\", \"wm_unittests\": \"6bf553030d01a18d514aafa0d8df9322b956664e\", \"wtf_unittests\": \"0fa6d82bc26246fef2bc427094045e855838c3ee\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610622148635471",
"status": "COMPLETED",
"status_changed_ts": "1610625930030570",
"tags": [
"build_address:luci.chromium.ci/win-asan/16814",
"builder:win-asan",
"buildset:commit/git/c808eb818c7db79963cba1b1543fb0c891f5d194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c808eb818c7db79963cba1b1543fb0c891f5d194",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023564050909216096",
"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/8858133988932613408/+/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:5119611f4636b010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610625930038166",
"url": "https://ci.chromium.org/b/8858133988932613408",
"utcnow_ts": "1610625930697378"
}
}
[12:05:30.706] Invocation finished in 1h3m6.297093015s with status SUCCEEDED