[07:59:02.552] New invocation is queued and will start shortly
[07:59:03.718] Starting the invocation (attempt 1)
[07:59:03.752] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:59:03.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:59:03.754] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025568720471298560",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"03b06d276741a6137a18915ca1a90d68b097ccd0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025568720471298560",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/03b06d276741a6137a18915ca1a90d68b097ccd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03b06d276741a6137a18915ca1a90d68b097ccd0",
"gitiles_ref:refs/heads/master"
]
}
[07:59:04.417] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608710343838828",
"id": "8860138658457951920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03b06d276741a6137a18915ca1a90d68b097ccd0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608710344201816",
"tags": [
"build_address:luci.chromium.ci/win-asan/16403",
"builder:win-asan",
"buildset:commit/git/03b06d276741a6137a18915ca1a90d68b097ccd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03b06d276741a6137a18915ca1a90d68b097ccd0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025568720471298560",
"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/8860138658457951920/+/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": "1608710344201926",
"url": "https://ci.chromium.org/b/8860138658457951920",
"utcnow_ts": "1608710344410700"
}
}
[07:59:04.417] Task URL: https://ci.chromium.org/b/8860138658457951920
[07:59:04.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:2:0) after 1m0s
[07:59:06.992] Received PubSub notification, asking Buildbucket for the build status
[07:59:07.072] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:04.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:2:0)
[08:00:04.454] Timer tick, asking Buildbucket for the build status
[08:00:04.575] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:04.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:4:0) after 1m0s
[08:01:04.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:4:0)
[08:01:04.989] Timer tick, asking Buildbucket for the build status
[08:01:05.396] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:05.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:5:0) after 1m0s
[08:02:05.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:5:0)
[08:02:05.436] Timer tick, asking Buildbucket for the build status
[08:02:05.788] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:05.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:6:0) after 1m0s
[08:03:05.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:6:0)
[08:03:05.884] Timer tick, asking Buildbucket for the build status
[08:03:06.585] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:06.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:7:0) after 1m0s
[08:04:06.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:7:0)
[08:04:06.617] Timer tick, asking Buildbucket for the build status
[08:04:06.785] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:06.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:8:0) after 1m0s
[08:05:06.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:8:0)
[08:05:06.860] Timer tick, asking Buildbucket for the build status
[08:05:06.929] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:06.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:9:0) after 1m0s
[08:06:07.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:9:0)
[08:06:07.041] Timer tick, asking Buildbucket for the build status
[08:06:07.184] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:07.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:10:0) after 1m0s
[08:07:07.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:10:0)
[08:07:07.285] Timer tick, asking Buildbucket for the build status
[08:07:07.402] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:07.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:11:0) after 1m0s
[08:08:10.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:11:0)
[08:08:10.210] Timer tick, asking Buildbucket for the build status
[08:08:10.346] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:10.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:12:0) after 1m0s
[08:09:10.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:12:0)
[08:09:10.337] Timer tick, asking Buildbucket for the build status
[08:09:10.522] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:10.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:13:0) after 1m0s
[08:10:10.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:13:0)
[08:10:10.604] Timer tick, asking Buildbucket for the build status
[08:10:10.724] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:10.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:14:0) after 1m0s
[08:11:10.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:14:0)
[08:11:10.747] Timer tick, asking Buildbucket for the build status
[08:11:10.835] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:15:0) after 1m0s
[08:12:10.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:15:0)
[08:12:10.879] Timer tick, asking Buildbucket for the build status
[08:12:11.006] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:11.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:16:0) after 1m0s
[08:13:11.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:16:0)
[08:13:11.025] Timer tick, asking Buildbucket for the build status
[08:13:11.305] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:11.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:17:0) after 1m0s
[08:14:11.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:17:0)
[08:14:11.399] Timer tick, asking Buildbucket for the build status
[08:14:11.530] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:11.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:18:0) after 1m0s
[08:15:11.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:18:0)
[08:15:11.553] Timer tick, asking Buildbucket for the build status
[08:15:11.653] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:19:0) after 1m0s
[08:16:11.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:19:0)
[08:16:11.810] Timer tick, asking Buildbucket for the build status
[08:16:12.517] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:12.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:20:0) after 1m0s
[08:17:12.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:20:0)
[08:17:12.571] Timer tick, asking Buildbucket for the build status
[08:17:12.678] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:12.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:21:0) after 1m0s
[08:18:12.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:21:0)
[08:18:12.746] Timer tick, asking Buildbucket for the build status
[08:18:12.860] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:12.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:22:0) after 1m0s
[08:19:12.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:22:0)
[08:19:12.895] Timer tick, asking Buildbucket for the build status
[08:19:13.045] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:13.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:23:0) after 1m0s
[08:20:13.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:23:0)
[08:20:13.193] Timer tick, asking Buildbucket for the build status
[08:20:13.314] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:13.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:24:0) after 1m0s
[08:21:13.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:24:0)
[08:21:13.459] Timer tick, asking Buildbucket for the build status
[08:21:13.877] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:13.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:25:0) after 1m0s
[08:22:13.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:25:0)
[08:22:13.901] Timer tick, asking Buildbucket for the build status
[08:22:13.988] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:13.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:26:0) after 1m0s
[08:23:14.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:26:0)
[08:23:14.261] Timer tick, asking Buildbucket for the build status
[08:23:14.375] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:14.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:27:0) after 1m0s
[08:24:14.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:27:0)
[08:24:14.483] Timer tick, asking Buildbucket for the build status
[08:24:14.635] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:14.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:28:0) after 1m0s
[08:25:14.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:28:0)
[08:25:14.739] Timer tick, asking Buildbucket for the build status
[08:25:14.831] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:14.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:29:0) after 1m0s
[08:26:14.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:29:0)
[08:26:14.865] Timer tick, asking Buildbucket for the build status
[08:26:14.954] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:14.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:30:0) after 1m0s
[08:27:14.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:30:0)
[08:27:14.986] Timer tick, asking Buildbucket for the build status
[08:27:15.067] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:15.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:31:0) after 1m0s
[08:28:15.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:31:0)
[08:28:15.085] Timer tick, asking Buildbucket for the build status
[08:28:15.469] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:15.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:32:0) after 1m0s
[08:29:15.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:32:0)
[08:29:15.491] Timer tick, asking Buildbucket for the build status
[08:29:15.606] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:15.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:33:0) after 1m0s
[08:30:15.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:33:0)
[08:30:15.660] Timer tick, asking Buildbucket for the build status
[08:30:15.742] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:15.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:34:0) after 1m0s
[08:31:15.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:34:0)
[08:31:15.760] Timer tick, asking Buildbucket for the build status
[08:31:16.269] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:16.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:35:0) after 1m0s
[08:32:16.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:35:0)
[08:32:16.341] Timer tick, asking Buildbucket for the build status
[08:32:16.478] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:16.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:36:0) after 1m0s
[08:33:16.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:36:0)
[08:33:16.508] Timer tick, asking Buildbucket for the build status
[08:33:16.645] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:16.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:37:0) after 1m0s
[08:34:16.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:37:0)
[08:34:16.651] Timer tick, asking Buildbucket for the build status
[08:34:16.797] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:16.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:38:0) after 1m0s
[08:35:16.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:38:0)
[08:35:16.887] Timer tick, asking Buildbucket for the build status
[08:35:17.449] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:17.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:39:0) after 1m0s
[08:36:17.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:39:0)
[08:36:17.511] Timer tick, asking Buildbucket for the build status
[08:36:17.769] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:17.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:40:0) after 1m0s
[08:37:17.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:40:0)
[08:37:17.831] Timer tick, asking Buildbucket for the build status
[08:37:18.025] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:18.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:41:0) after 1m0s
[08:38:18.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:41:0)
[08:38:18.110] Timer tick, asking Buildbucket for the build status
[08:38:18.525] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:18.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:42:0) after 1m0s
[08:39:18.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:42:0)
[08:39:18.537] Timer tick, asking Buildbucket for the build status
[08:39:18.743] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:18.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:43:0) after 1m0s
[08:40:18.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:43:0)
[08:40:18.843] Timer tick, asking Buildbucket for the build status
[08:40:19.174] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:19.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:44:0) after 1m0s
[08:41:19.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:44:0)
[08:41:19.271] Timer tick, asking Buildbucket for the build status
[08:41:19.369] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:19.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:45:0) after 1m0s
[08:42:19.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:45:0)
[08:42:19.467] Timer tick, asking Buildbucket for the build status
[08:42:19.826] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:19.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:46:0) after 1m0s
[08:43:19.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:46:0)
[08:43:19.872] Timer tick, asking Buildbucket for the build status
[08:43:19.957] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:19.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:47:0) after 1m0s
[08:44:19.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:47:0)
[08:44:19.978] Timer tick, asking Buildbucket for the build status
[08:44:20.699] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:20.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:48:0) after 1m0s
[08:45:20.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:48:0)
[08:45:20.719] Timer tick, asking Buildbucket for the build status
[08:45:20.924] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:20.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:49:0) after 1m0s
[08:46:20.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:49:0)
[08:46:20.976] Timer tick, asking Buildbucket for the build status
[08:46:21.039] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:50:0) after 1m0s
[08:47:21.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:50:0)
[08:47:21.061] Timer tick, asking Buildbucket for the build status
[08:47:21.132] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:21.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:51:0) after 1m0s
[08:48:21.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:51:0)
[08:48:21.155] Timer tick, asking Buildbucket for the build status
[08:48:21.245] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:21.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:52:0) after 1m0s
[08:49:21.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:52:0)
[08:49:21.274] Timer tick, asking Buildbucket for the build status
[08:49:21.372] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:21.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:53:0) after 1m0s
[08:50:21.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:53:0)
[08:50:21.398] Timer tick, asking Buildbucket for the build status
[08:50:21.477] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:21.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:54:0) after 1m0s
[08:51:21.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:54:0)
[08:51:21.577] Timer tick, asking Buildbucket for the build status
[08:51:21.826] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:21.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:55:0) after 1m0s
[08:52:21.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:55:0)
[08:52:21.941] Timer tick, asking Buildbucket for the build status
[08:52:21.996] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:21.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:56:0) after 1m0s
[08:53:21.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:56:0)
[08:53:21.996] Timer tick, asking Buildbucket for the build status
[08:53:22.133] Build 8860138658457951920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:22.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025568720471298560:57:0) after 1m0s
[08:53:49.263] Received PubSub notification, asking Buildbucket for the build status
[08:53:49.355] Build 8860138658457951920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:53:49.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608713628439064",
"created_by": "project:chromium",
"created_ts": "1608710343838828",
"id": "8860138658457951920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03b06d276741a6137a18915ca1a90d68b097ccd0\"}}",
"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\": \"03b06d276741a6137a18915ca1a90d68b097ccd0\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16403, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"5c0ba5437be05c887189a566e4f99220b68ec8ca\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"03b06d276741a6137a18915ca1a90d68b097ccd0\", \"got_revision_cp\": \"refs/heads/master@{#839069}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"407251b826bdb2683e639456c81b4a82de640240\", \"got_v8_revision_cp\": \"refs/heads/8.9.185@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03b06d276741a6137a18915ca1a90d68b097ccd0\", \"swarm_hashes_refs/heads/master(at){#839069}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"30fbd9393531324c0bbea130e55df128605896de\", \"angle_unittests\": \"93d2c285464b0022b9c1f3ef183400d1a8710796\", \"app_shell_unittests\": \"42f234b64ac6ac450832e68962b23978e66f31eb\", \"aura_unittests\": \"643e67ba3918e7a9119ce40a13b5d77879e8af0a\", \"base_unittests\": \"7ca7a1bb35aac42e0d7146e65d0f45bccbd8b1d1\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"579970202a6c763d199d101032cb7d6951633e4a\", \"blink_fuzzer_unittests\": \"a39cbf9705a8843bdba0ca95f1043225a55a4b33\", \"blink_heap_unittests\": \"f6061e544e9ae97137593120a5c5d8bf092e27b4\", \"blink_platform_unittests\": \"76911756592b7f146f9075c0c31df0ed71bc847c\", \"blink_unittests\": \"b26b0dfd68b012890aff2d5bdcadabb205ae4381\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"5557ac92dbddae9e5d5a0c5bcef74925bc36c20d\", \"capture_unittests\": \"bc09cf9ebd8bea70ad05033748e39d20294bcc4d\", \"cast_unittests\": \"8113c2a90227d9beb633437344e8cbc5208f5788\", \"cc_unittests\": \"d3e862ccd4ec8597095ad5ebc712ad3979fd61c0\", \"chrome_app_unittests\": \"783eaeeb0470149c969937769884a6716afe1311\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"85899d06b778c11a29575bab1e96db606e6f1974\", \"chromedriver_unittests\": \"7a12946fc319489facb7f97e8cd005d41a4798f7\", \"components_browsertests\": \"ac7ec2939750a293fa4d2b58bfbaf72c2dfc210a\", \"components_unittests\": \"d99ff016b1ce4c5535c671a126b4e222005e68a5\", \"compositor_unittests\": \"ac4d2106a694f8a9a49ded3596053592b9917f32\", \"content_browsertests\": \"634b3ec942d5d8e1815e7b37cb8c1fa4d7d6fd55\", \"content_unittests\": \"51cc8e0fa6f07aec45bd13397d0a5d981f0d45ed\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"a1cd3290fedbf81bf5e13fdfab919341d83baa0b\", \"device_unittests\": \"b67ba0d4d9338396665dbb01f89e420f67639a9a\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"059b77ea9fe844f38cfead396a563de42c7200cf\", \"events_unittests\": \"4743b0c8c7419241602fd5490ebb847fb4a1ff66\", \"extensions_browsertests\": \"7a05a1db9396d1bc3f151cf79faff948e3e8e513\", \"extensions_unittests\": \"79da9742340c652ecae1ca0b8ebf7da1d5c48b74\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"a82475cbee5ce1786400b8437be1427befc8afb6\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"668b69a54f6304263cf9b4e09ce456d9c2fbe353\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"0166b483954cfc94459fee6da2862f0a9f5bd318\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"a283f70ea4fc000ea671b35a24af7f7b1cdf6a4d\", \"headless_unittests\": \"0bf8278077998d482ed2dce0c7eeeb9d70ccfed4\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"013b3988d16341d59299359a5a69bf58a3ebb701\", \"interactive_ui_tests\": \"f3b9927f5943f1d6487a7bf738c45954030d36bf\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"d980537f3852abfd4605faaff9d2c89d4aa202d5\", \"media_unittests\": \"03aa933f59e6e796468832f9d65eed2c0eac12ad\", \"message_center_unittests\": \"ceb301a7a4224ad1b79ef6c9aa64fafebb05c0df\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"09c84078031c849c996f477694cd0c74ed31cd1f\", \"nacl_loader_unittests\": \"6b660a5d99011937fd46a6ed983a783cbf766a33\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"352494affe15154977ae6d9466aa63348362df4e\", \"pdf_unittests\": \"4678a3e4fe5a6a4dab13d08dd7e0b942663c0b5f\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"4726cebb66c658feeabc009b95372f24ff341a94\", \"printing_unittests\": \"dcc59e2a86ee37a135b8d78ed2dc16022848bbf8\", \"remoting_unittests\": \"cebfffd5b4840d10457f35339ab202420ba7d346\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"bce4ed8e91b2612523922438c0c5184bf46c19b9\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"b9377688f01d25509b5fad62a6d004b6303b067c\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"c1931f433afb3ee140ef079b8365b8933a1ea9f6\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"e56d9586c20b56b2db983d2710ffdd7caf71a252\", \"sync_integration_tests\": \"a2157e3dc39914933f790502b55fc4e0a90298aa\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"cf94a17b48a113229fb31b50914d6d59b62944fc\", \"ui_touch_selection_unittests\": \"4b22030c95d4cefd7369b52eb8fa0a0a98dd6456\", \"unit_tests\": \"adae1ac9c4e4d314c57d4f85dd64e6b707c14444\", \"updater_tests\": \"71f0e2b9e12cc7d9e65fc3ac1644742888a45d82\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"bf73428ab6a1776b963fcab57c2b63ecf71c9144\", \"viz_unittests\": \"ac66fce68030200a999260ef28249683117b9b86\", \"vr_common_unittests\": \"eb6399fe88fd6db7b154976b24fe4f4210e7265f\", \"vr_pixeltests\": \"7fa4c7f3a2d442c369f6fac6c33f1122cb3bfec5\", \"weblayer_browsertests\": \"78ab5d45323216c5b166ca7647ccdefe60da84eb\", \"weblayer_unittests\": \"732f91c90983d808f5c7ecc08686b26db802cd6a\", \"wm_unittests\": \"55aef6fb130b4923ef2de57e18ccb98c23a55169\", \"wtf_unittests\": \"c50cc85313eefe61cf37c6dc855db98fac6976c2\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-gpyc\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608710346341838",
"status": "COMPLETED",
"status_changed_ts": "1608713628689853",
"tags": [
"build_address:luci.chromium.ci/win-asan/16403",
"builder:win-asan",
"buildset:commit/git/03b06d276741a6137a18915ca1a90d68b097ccd0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03b06d276741a6137a18915ca1a90d68b097ccd0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025568720471298560",
"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/8860138658457951920/+/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:50a76d5956856a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608713628694010",
"url": "https://ci.chromium.org/b/8860138658457951920",
"utcnow_ts": "1608713629332847"
}
}
[08:53:49.355] Invocation finished in 54m46.820799471s with status SUCCEEDED