[07:58:14.215] New invocation is queued and will start shortly
[07:58:15.347] Starting the invocation (attempt 1)
[07:58:15.377] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:58:15.377] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:58:15.377] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020132953172615264",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f489cb525b8e4d70452eaf4470120cbb100a4395\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020132953172615264",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f489cb525b8e4d70452eaf4470120cbb100a4395",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f489cb525b8e4d70452eaf4470120cbb100a4395",
"gitiles_ref:refs/heads/master"
]
}
[07:58:16.728] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1613894295508114",
"id": "8854702891151237728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f489cb525b8e4d70452eaf4470120cbb100a4395\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613894296423526",
"tags": [
"build_address:luci.chromium.ci/win-asan/17457",
"builder:win-asan",
"buildset:commit/git/f489cb525b8e4d70452eaf4470120cbb100a4395",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f489cb525b8e4d70452eaf4470120cbb100a4395",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020132953172615264",
"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/8854702891151237728/+/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": "1613894296423657",
"url": "https://ci.chromium.org/b/8854702891151237728",
"utcnow_ts": "1613894296717494"
}
}
[07:58:16.728] Task URL: https://ci.chromium.org/b/8854702891151237728
[07:58:16.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:2:0) after 1m0s
[07:58:34.676] Received PubSub notification, asking Buildbucket for the build status
[07:58:34.753] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:16.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:2:0)
[07:59:16.743] Timer tick, asking Buildbucket for the build status
[07:59:16.809] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:16.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:4:0) after 1m0s
[08:00:16.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:4:0)
[08:00:16.886] Timer tick, asking Buildbucket for the build status
[08:00:17.034] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:17.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:5:0) after 1m0s
[08:01:17.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:5:0)
[08:01:17.094] Timer tick, asking Buildbucket for the build status
[08:01:17.344] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:17.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:6:0) after 1m0s
[08:02:17.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:6:0)
[08:02:17.445] Timer tick, asking Buildbucket for the build status
[08:02:17.507] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:17.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:7:0) after 1m0s
[08:03:17.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:7:0)
[08:03:17.546] Timer tick, asking Buildbucket for the build status
[08:03:17.647] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:17.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:8:0) after 1m0s
[08:04:17.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:8:0)
[08:04:17.665] Timer tick, asking Buildbucket for the build status
[08:04:18.334] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:18.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:9:0) after 1m0s
[08:05:18.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:9:0)
[08:05:18.349] Timer tick, asking Buildbucket for the build status
[08:05:18.424] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:18.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:10:0) after 1m0s
[08:06:18.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:10:0)
[08:06:18.489] Timer tick, asking Buildbucket for the build status
[08:06:18.565] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:18.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:11:0) after 1m0s
[08:07:18.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:11:0)
[08:07:18.611] Timer tick, asking Buildbucket for the build status
[08:07:18.813] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:18.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:12:0) after 1m0s
[08:08:18.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:12:0)
[08:08:18.928] Timer tick, asking Buildbucket for the build status
[08:08:19.016] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:19.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:13:0) after 1m0s
[08:09:21.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:13:0)
[08:09:21.516] Timer tick, asking Buildbucket for the build status
[08:09:21.581] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:21.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:14:0) after 1m0s
[08:10:21.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:14:0)
[08:10:21.807] Timer tick, asking Buildbucket for the build status
[08:10:22.108] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:22.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:15:0) after 1m0s
[08:11:22.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:15:0)
[08:11:22.221] Timer tick, asking Buildbucket for the build status
[08:11:22.285] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:22.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:16:0) after 1m0s
[08:12:22.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:16:0)
[08:12:22.326] Timer tick, asking Buildbucket for the build status
[08:12:22.397] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:22.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:17:0) after 1m0s
[08:13:22.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:17:0)
[08:13:22.588] Timer tick, asking Buildbucket for the build status
[08:13:22.816] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:22.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:18:0) after 1m0s
[08:14:22.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:18:0)
[08:14:22.978] Timer tick, asking Buildbucket for the build status
[08:14:23.056] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:23.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:19:0) after 1m0s
[08:15:23.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:19:0)
[08:15:23.209] Timer tick, asking Buildbucket for the build status
[08:15:23.297] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:23.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:20:0) after 1m0s
[08:16:24.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:20:0)
[08:16:24.526] Timer tick, asking Buildbucket for the build status
[08:16:24.613] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:24.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:21:0) after 1m0s
[08:17:24.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:21:0)
[08:17:24.683] Timer tick, asking Buildbucket for the build status
[08:17:24.767] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:24.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:22:0) after 1m0s
[08:18:24.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:22:0)
[08:18:24.907] Timer tick, asking Buildbucket for the build status
[08:18:25.152] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:25.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:23:0) after 1m0s
[08:19:25.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:23:0)
[08:19:25.269] Timer tick, asking Buildbucket for the build status
[08:19:25.347] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:25.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:24:0) after 1m0s
[08:20:25.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:24:0)
[08:20:25.481] Timer tick, asking Buildbucket for the build status
[08:20:25.726] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:25.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:25:0) after 1m0s
[08:21:25.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:25:0)
[08:21:25.806] Timer tick, asking Buildbucket for the build status
[08:21:25.937] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:25.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:26:0) after 1m0s
[08:22:25.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:26:0)
[08:22:25.955] Timer tick, asking Buildbucket for the build status
[08:22:26.053] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:26.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:27:0) after 1m0s
[08:23:26.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:27:0)
[08:23:26.188] Timer tick, asking Buildbucket for the build status
[08:23:26.286] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:26.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:28:0) after 1m0s
[08:24:26.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:28:0)
[08:24:26.364] Timer tick, asking Buildbucket for the build status
[08:24:26.446] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:26.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:29:0) after 1m0s
[08:25:26.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:29:0)
[08:25:26.579] Timer tick, asking Buildbucket for the build status
[08:25:26.754] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:26.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:30:0) after 1m0s
[08:26:26.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:30:0)
[08:26:26.792] Timer tick, asking Buildbucket for the build status
[08:26:26.886] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:26.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:31:0) after 1m0s
[08:27:26.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:31:0)
[08:27:26.989] Timer tick, asking Buildbucket for the build status
[08:27:27.245] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:27.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:32:0) after 1m0s
[08:28:27.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:32:0)
[08:28:27.297] Timer tick, asking Buildbucket for the build status
[08:28:27.405] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:27.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:33:0) after 1m0s
[08:29:27.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:33:0)
[08:29:27.461] Timer tick, asking Buildbucket for the build status
[08:29:27.551] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:27.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:34:0) after 1m0s
[08:30:27.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:34:0)
[08:30:27.672] Timer tick, asking Buildbucket for the build status
[08:30:27.819] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:27.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:35:0) after 1m0s
[08:31:27.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:35:0)
[08:31:27.865] Timer tick, asking Buildbucket for the build status
[08:31:27.956] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:27.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:36:0) after 1m0s
[08:32:27.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:36:0)
[08:32:27.990] Timer tick, asking Buildbucket for the build status
[08:32:28.077] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:28.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:37:0) after 1m0s
[08:33:28.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:37:0)
[08:33:28.157] Timer tick, asking Buildbucket for the build status
[08:33:28.258] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:38:0) after 1m0s
[08:34:28.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:38:0)
[08:34:28.292] Timer tick, asking Buildbucket for the build status
[08:34:28.379] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:28.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:39:0) after 1m0s
[08:35:28.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:39:0)
[08:35:28.404] Timer tick, asking Buildbucket for the build status
[08:35:28.489] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:28.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:40:0) after 1m0s
[08:36:28.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:40:0)
[08:36:28.568] Timer tick, asking Buildbucket for the build status
[08:36:28.650] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:28.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:41:0) after 1m0s
[08:37:28.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:41:0)
[08:37:28.668] Timer tick, asking Buildbucket for the build status
[08:37:28.821] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:28.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:42:0) after 1m0s
[08:38:28.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:42:0)
[08:38:28.887] Timer tick, asking Buildbucket for the build status
[08:38:29.197] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:43:0) after 1m0s
[08:39:29.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:43:0)
[08:39:29.243] Timer tick, asking Buildbucket for the build status
[08:39:29.353] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:29.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:44:0) after 1m0s
[08:40:29.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:44:0)
[08:40:29.383] Timer tick, asking Buildbucket for the build status
[08:40:29.485] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:29.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:45:0) after 1m0s
[08:41:29.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:45:0)
[08:41:29.531] Timer tick, asking Buildbucket for the build status
[08:41:29.631] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:29.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:46:0) after 1m0s
[08:42:29.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:46:0)
[08:42:29.676] Timer tick, asking Buildbucket for the build status
[08:42:29.755] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:29.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:47:0) after 1m0s
[08:43:29.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:47:0)
[08:43:29.816] Timer tick, asking Buildbucket for the build status
[08:43:29.915] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:29.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:48:0) after 1m0s
[08:44:29.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:48:0)
[08:44:29.978] Timer tick, asking Buildbucket for the build status
[08:44:31.353] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:31.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:49:0) after 1m0s
[08:45:31.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:49:0)
[08:45:31.453] Timer tick, asking Buildbucket for the build status
[08:45:31.844] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:31.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:50:0) after 1m0s
[08:46:31.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:50:0)
[08:46:31.861] Timer tick, asking Buildbucket for the build status
[08:46:32.020] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:32.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:51:0) after 1m0s
[08:47:32.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:51:0)
[08:47:32.033] Timer tick, asking Buildbucket for the build status
[08:47:32.306] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:32.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:52:0) after 1m0s
[08:48:32.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:52:0)
[08:48:32.380] Timer tick, asking Buildbucket for the build status
[08:48:32.466] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:32.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:53:0) after 1m0s
[08:49:32.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:53:0)
[08:49:32.498] Timer tick, asking Buildbucket for the build status
[08:49:32.594] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:32.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:54:0) after 1m0s
[08:50:32.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:54:0)
[08:50:32.632] Timer tick, asking Buildbucket for the build status
[08:50:33.047] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:33.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:55:0) after 1m0s
[08:51:33.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:55:0)
[08:51:33.039] Timer tick, asking Buildbucket for the build status
[08:51:33.128] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:33.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:56:0) after 1m0s
[08:52:33.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:56:0)
[08:52:33.228] Timer tick, asking Buildbucket for the build status
[08:52:33.311] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:33.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:57:0) after 1m0s
[08:53:33.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:57:0)
[08:53:33.328] Timer tick, asking Buildbucket for the build status
[08:53:33.470] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:33.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:58:0) after 1m0s
[08:54:33.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:58:0)
[08:54:33.438] Timer tick, asking Buildbucket for the build status
[08:54:33.538] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:33.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:59:0) after 1m0s
[08:55:33.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:59:0)
[08:55:33.602] Timer tick, asking Buildbucket for the build status
[08:55:33.679] Build 8854702891151237728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:33.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020132953172615264:60:0) after 1m0s
[08:56:23.683] Received PubSub notification, asking Buildbucket for the build status
[08:56:23.791] Build 8854702891151237728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:56:23.792] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613897782712336",
"created_by": "project:chromium",
"created_ts": "1613894295508114",
"id": "8854702891151237728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f489cb525b8e4d70452eaf4470120cbb100a4395\"}}",
"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\": \"f489cb525b8e4d70452eaf4470120cbb100a4395\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-r8z7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17457, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"c03a42358c05ceedd15f0fca50292b756c2fad57\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"f489cb525b8e4d70452eaf4470120cbb100a4395\", \"got_revision_cp\": \"refs/heads/master@{#856135}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"f489cb525b8e4d70452eaf4470120cbb100a4395\", \"swarm_hashes_refs/heads/master(at){#856135}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"d36a68bbcf722144b491ab7ee4dbf378c9a88637\", \"angle_unittests\": \"ad4af5a5d73f9f7387365242d6009f9aba90c985\", \"app_shell_unittests\": \"c9e33c78c346bb0c80892b570428fd4e608aaefd\", \"aura_unittests\": \"ddd8e2333b7cd1cfe5ea0cfa1c2a971e2f3ad2f8\", \"base_unittests\": \"1ceb22df0f743b895465b4bef1cb2600b451aa3c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"5f71520cce6b8bd71693dcbc969a3f1329d6361b\", \"blink_fuzzer_unittests\": \"1399f1fb24a115695d85d0649828b7e9a4cca5d9\", \"blink_heap_unittests\": \"9ab1b6e402464fd3947ba0ab6d1ec23af86f1eeb\", \"blink_platform_unittests\": \"2e25579710db299a90f832ee6bbafe2ff137f002\", \"blink_unittests\": \"f4f05e1197be8085b4552b7e33fee8be7f2ff71c\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"d1dcf79cae34a3085135f8217955eeedf5199288\", \"capture_unittests\": \"d01bbac85bcaf2712a056b6406949580e9be7fbf\", \"cast_unittests\": \"f795d62e67df93b2905b09d4ed53b88c7b591757\", \"cc_unittests\": \"5719ab8973e56829f76fc2363b636b7feadb6b92\", \"chrome_app_unittests\": \"fddf2a92d54b8e189a5faa6bc9badbf76fc17e1f\", \"chrome_cleaner_unittests\": \"55e2f47cd864115643d7551ea3c064e2464eb5fc\", \"chrome_elf_unittests\": \"18fa50c76015be9a12548eba066ab04c10ebda4f\", \"chromedriver_unittests\": \"cf986b473ffe4d32b1b466ea63427a234819bbdb\", \"components_browsertests\": \"affeb3ca6b23e37d956af1e34458e6eae51d1432\", \"components_unittests\": \"ac7c3534aae23027c1cf2108b4ac337def48d237\", \"compositor_unittests\": \"1f76e706ca79f5425bea6f67ca746e1324a3f23d\", \"content_browsertests\": \"ecd730b21d65e22ffc42760a6afca83dc5e50647\", \"content_unittests\": \"555b4053f2c3efe28e4f7bc89714019878518fd5\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"9a83294f13ad35a6cf82fdebc717dfbac040d35a\", \"cronet_unittests\": \"4979a12864a1c4e1330e7ce891d4369a2e32a4ad\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"71a198ae656ca14b5d555d5c2cec5176f16b3579\", \"device_unittests\": \"d6a0754810aae1f8324b8501d148d8b7dbcf1e2e\", \"display_unittests\": \"0c4f5d584e2c1c5eeff2180855204802fdae2639\", \"elevation_service_unittests\": \"568f3779a782634b1b1f12bde135fdce611469c2\", \"events_unittests\": \"6dbce8c362ce2dcdb7a0ef71ea39b3999a4832c9\", \"extensions_browsertests\": \"e368c4a00311fac98820cc8a676bcd2620386eb8\", \"extensions_unittests\": \"5b881235b79a87b72757b1ea4610995806a8b7e2\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"24851e5cf10fc135255713fac97c693863cf0a73\", \"gcp_unittests\": \"1d7f28ea30541e4eb5389588f5a9672f71b6c4b5\", \"gfx_unittests\": \"de4731e4450e4ae75f8402a6842184c59e009ca0\", \"gin_unittests\": \"4870a5d97c4ee8fa44241007482db1c691266529\", \"google_apis_unittests\": \"c7d3521637b26bdfac5393d97b0e280bd8bde35a\", \"gpu_unittests\": \"2bd38462b07a98c948b0765d144f7296e0ce1913\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"9a2b0b731c13d6dc69bef851fed16a8d028b0063\", \"headless_unittests\": \"97b5a854f5565e20ad9ae2716c42cde5e0c488b3\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"3c9975d9e445a02fd9f2f02a7e61138398261732\", \"interactive_ui_tests\": \"8f0f7fff454b165e3e0112a659a47860d1737ab7\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"2387f7f8ccaf3b5e86c265186b2747625bcbec3a\", \"latency_unittests\": \"810a5adc2e5f3a5f4d7e0e85c967d34de4c093c6\", \"libjingle_xmpp_unittests\": \"1838b44993b93f95f7ac96d3353eea76732d49b2\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"3874c1450f7a4bd054ed87a17c2673a7af2342b6\", \"media_unittests\": \"b92ba05add6a1452314adabe4b3fa99a633db088\", \"message_center_unittests\": \"6946ae6c987bbb2f9155ab1fb7440bae7128bf70\", \"midi_unittests\": \"ef799d0780a0fd3a9692026fb544836f013c613c\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"b846f1d826af9534ea90171fa5064275a3beb64b\", \"nacl_loader_unittests\": \"0b1a930b63cc5ec9f5d210949507de0761d68269\", \"native_theme_unittests\": \"0b69c3d475061b683ff6b60101272ada5bceaba7\", \"net_unittests\": \"ee6230e4671b3460bde0ccfb2bf6bc122e624afc\", \"notification_helper_unittests\": \"8262ce378a7ac95665f0bec1c3f861e8cbd2f706\", \"pdf_unittests\": \"59a529ed8afd1932303586a90964f30f364b8f23\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"ad7a20b0123e3c6982de61db5e0541a1703db236\", \"printing_unittests\": \"f55baffec45ac5d76450da54a8616c47248ca79a\", \"remoting_unittests\": \"5f0d6515f9a46ad67acafa292f388c950a07902d\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"84e59031c18384eede41ef5a79400408a363417b\", \"services_unittests\": \"0b5fdf099976fc7b0de21ebaf33dcf90943580fe\", \"setup_unittests\": \"e5b7ce1c0661e4138cd162daeca42af49e637f03\", \"shell_dialogs_unittests\": \"3f06e99d6b1826ca8aa3e10a926ac6086815f0b5\", \"skia_unittests\": \"80c5fcbe9de85e38a92a4e3fcddf4efac133b38a\", \"snapshot_unittests\": \"40655a841531384b44b20ad0fe9a6055e3b45317\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"d81b0eed472741133493386c102f0f371b881657\", \"sync_integration_tests\": \"b9a65f3a4aa13551a3712852e4bbde14708fc499\", \"traffic_annotation_auditor_unittests\": \"774059a4d1525c99a60dd3eee241c4046b561136\", \"ui_base_unittests\": \"4f2d22af6e89a76a32fe5fc833e07685464ecad3\", \"ui_touch_selection_unittests\": \"48ecc69f3da2b09bc66aa456f870eb12e3ad04f3\", \"unit_tests\": \"e9fa86e3470c31f36252794ca064db72397d8a89\", \"updater_tests\": \"8450faef38ff862f5f5e203a7e17ae47d77cd4f1\", \"url_unittests\": \"6902a7d04e4fa2df1a806861575fd250457a55e2\", \"views_unittests\": \"ed1efabc34ffabf0c119e7a764e1658d62c907aa\", \"viz_unittests\": \"9ef7eeca247aca159c006670e6c78f1c9e69caf5\", \"vr_common_unittests\": \"de9cdf596bee31dd37edd8c743cebb92c3ff1400\", \"vr_pixeltests\": \"64ebda5bea998072516548073ad97639517f993d\", \"weblayer_browsertests\": \"8fa509f77e3b417d34bf7b2bf23db32fde5fa914\", \"weblayer_unittests\": \"f4698f951cd81d818765f1dc267b327037a4559d\", \"wm_unittests\": \"a7686d12198aceb3f1f3a0dcd5027b7a4bd7e3f6\", \"wtf_unittests\": \"4fabb5215ec20edbdf1373d84b6cf0b9df2421d1\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-r8z7\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613894313878215",
"status": "COMPLETED",
"status_changed_ts": "1613897782948068",
"tags": [
"build_address:luci.chromium.ci/win-asan/17457",
"builder:win-asan",
"buildset:commit/git/f489cb525b8e4d70452eaf4470120cbb100a4395",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f489cb525b8e4d70452eaf4470120cbb100a4395",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020132953172615264",
"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/8854702891151237728/+/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:51dc6a2cf49f5810",
"user_agent:luci-scheduler"
],
"updated_ts": "1613897782952128",
"url": "https://ci.chromium.org/b/8854702891151237728",
"utcnow_ts": "1613897783778372"
}
}
[08:56:23.793] Invocation finished in 58m9.595497334s with status SUCCEEDED