[16:15:02.456] New invocation is queued and will start shortly
[16:15:03.958] Starting the invocation (attempt 1)
[16:15:03.991] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:15:03.991] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:15:03.992] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027983633043049312",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027983633043049312",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"gitiles_ref:refs/heads/master"
]
}
[16:15:05.155] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606407304097616",
"id": "8862553570657504224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606407304852484",
"tags": [
"build_address:luci.chromium.ci/win-asan/15866",
"builder:win-asan",
"buildset:commit/git/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027983633043049312",
"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/8862553570657504224/+/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": "1606407304852643",
"url": "https://ci.chromium.org/b/8862553570657504224",
"utcnow_ts": "1606407305127297"
}
}
[16:15:05.155] Task URL: https://ci.chromium.org/b/8862553570657504224
[16:15:05.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:2:0) after 1m0s
[16:15:07.562] Received PubSub notification, asking Buildbucket for the build status
[16:15:08.158] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:05.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:2:0)
[16:16:05.190] Timer tick, asking Buildbucket for the build status
[16:16:05.315] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:05.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:4:0) after 1m0s
[16:17:05.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:4:0)
[16:17:05.411] Timer tick, asking Buildbucket for the build status
[16:17:05.836] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:05.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:5:0) after 1m0s
[16:18:05.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:5:0)
[16:18:05.979] Timer tick, asking Buildbucket for the build status
[16:18:07.072] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:07.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:6:0) after 1m0s
[16:19:07.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:6:0)
[16:19:07.129] Timer tick, asking Buildbucket for the build status
[16:19:07.351] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:07.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:7:0) after 1m0s
[16:20:07.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:7:0)
[16:20:07.439] Timer tick, asking Buildbucket for the build status
[16:20:07.613] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:07.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:8:0) after 1m0s
[16:21:07.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:8:0)
[16:21:07.636] Timer tick, asking Buildbucket for the build status
[16:21:07.835] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:07.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:9:0) after 1m0s
[16:22:07.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:9:0)
[16:22:07.933] Timer tick, asking Buildbucket for the build status
[16:22:08.533] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:08.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:10:0) after 1m0s
[16:23:08.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:10:0)
[16:23:08.551] Timer tick, asking Buildbucket for the build status
[16:23:08.604] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:08.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:11:0) after 1m0s
[16:24:08.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:11:0)
[16:24:08.684] Timer tick, asking Buildbucket for the build status
[16:24:08.833] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:08.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:12:0) after 1m0s
[16:25:08.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:12:0)
[16:25:08.907] Timer tick, asking Buildbucket for the build status
[16:25:09.217] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:09.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:13:0) after 1m0s
[16:26:09.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:13:0)
[16:26:09.268] Timer tick, asking Buildbucket for the build status
[16:26:09.407] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:09.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:14:0) after 1m0s
[16:27:09.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:14:0)
[16:27:09.485] Timer tick, asking Buildbucket for the build status
[16:27:09.645] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:09.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:15:0) after 1m0s
[16:28:09.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:15:0)
[16:28:09.662] Timer tick, asking Buildbucket for the build status
[16:28:09.759] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:09.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:16:0) after 1m0s
[16:29:09.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:16:0)
[16:29:09.811] Timer tick, asking Buildbucket for the build status
[16:29:09.953] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:09.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:17:0) after 1m0s
[16:30:10.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:17:0)
[16:30:10.087] Timer tick, asking Buildbucket for the build status
[16:30:10.486] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:10.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:18:0) after 1m0s
[16:31:10.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:18:0)
[16:31:10.513] Timer tick, asking Buildbucket for the build status
[16:31:10.642] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:10.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:19:0) after 1m0s
[16:32:10.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:19:0)
[16:32:10.683] Timer tick, asking Buildbucket for the build status
[16:32:11.392] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:11.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:20:0) after 1m0s
[16:33:11.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:20:0)
[16:33:11.433] Timer tick, asking Buildbucket for the build status
[16:33:11.760] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:11.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:21:0) after 1m0s
[16:34:11.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:21:0)
[16:34:11.826] Timer tick, asking Buildbucket for the build status
[16:34:12.081] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:12.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:22:0) after 1m0s
[16:35:12.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:22:0)
[16:35:12.238] Timer tick, asking Buildbucket for the build status
[16:35:12.778] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:12.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:23:0) after 1m0s
[16:36:12.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:23:0)
[16:36:12.771] Timer tick, asking Buildbucket for the build status
[16:36:13.038] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:13.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:24:0) after 1m0s
[16:37:13.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:24:0)
[16:37:13.043] Timer tick, asking Buildbucket for the build status
[16:37:13.164] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:13.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:25:0) after 1m0s
[16:38:13.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:25:0)
[16:38:13.191] Timer tick, asking Buildbucket for the build status
[16:38:13.677] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:13.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:26:0) after 1m0s
[16:39:13.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:26:0)
[16:39:13.657] Timer tick, asking Buildbucket for the build status
[16:39:13.864] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:13.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:27:0) after 1m0s
[16:40:13.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:27:0)
[16:40:13.884] Timer tick, asking Buildbucket for the build status
[16:40:14.346] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:14.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:28:0) after 1m0s
[16:41:14.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:28:0)
[16:41:14.408] Timer tick, asking Buildbucket for the build status
[16:41:14.514] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:14.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:29:0) after 1m0s
[16:42:14.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:29:0)
[16:42:14.562] Timer tick, asking Buildbucket for the build status
[16:42:14.733] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:14.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:30:0) after 1m0s
[16:43:14.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:30:0)
[16:43:14.839] Timer tick, asking Buildbucket for the build status
[16:43:15.279] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:15.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:31:0) after 1m0s
[16:44:15.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:31:0)
[16:44:15.331] Timer tick, asking Buildbucket for the build status
[16:44:15.474] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:15.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:32:0) after 1m0s
[16:45:15.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:32:0)
[16:45:15.495] Timer tick, asking Buildbucket for the build status
[16:45:15.651] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:15.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:33:0) after 1m0s
[16:46:15.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:33:0)
[16:46:15.648] Timer tick, asking Buildbucket for the build status
[16:46:15.784] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:15.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:34:0) after 1m0s
[16:47:15.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:34:0)
[16:47:15.857] Timer tick, asking Buildbucket for the build status
[16:47:15.986] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:15.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:35:0) after 1m0s
[16:48:16.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:35:0)
[16:48:16.024] Timer tick, asking Buildbucket for the build status
[16:48:16.579] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:16.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:36:0) after 1m0s
[16:49:16.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:36:0)
[16:49:16.603] Timer tick, asking Buildbucket for the build status
[16:49:16.696] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:37:0) after 1m0s
[16:50:16.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:37:0)
[16:50:16.752] Timer tick, asking Buildbucket for the build status
[16:50:17.439] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:17.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:38:0) after 1m0s
[16:51:17.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:38:0)
[16:51:17.516] Timer tick, asking Buildbucket for the build status
[16:51:17.919] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:17.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:39:0) after 1m0s
[16:52:17.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:39:0)
[16:52:17.965] Timer tick, asking Buildbucket for the build status
[16:52:18.164] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:18.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:40:0) after 1m0s
[16:53:18.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:40:0)
[16:53:18.205] Timer tick, asking Buildbucket for the build status
[16:53:18.338] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:18.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:41:0) after 1m0s
[16:54:18.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:41:0)
[16:54:18.337] Timer tick, asking Buildbucket for the build status
[16:54:18.543] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:42:0) after 1m0s
[16:55:18.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:42:0)
[16:55:18.619] Timer tick, asking Buildbucket for the build status
[16:55:18.759] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:18.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:43:0) after 1m0s
[16:56:18.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:43:0)
[16:56:18.857] Timer tick, asking Buildbucket for the build status
[16:56:18.969] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:18.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:44:0) after 1m0s
[16:57:19.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:44:0)
[16:57:19.001] Timer tick, asking Buildbucket for the build status
[16:57:19.065] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:19.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:45:0) after 1m0s
[16:58:19.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:45:0)
[16:58:19.111] Timer tick, asking Buildbucket for the build status
[16:58:19.429] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:19.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:46:0) after 1m0s
[16:59:19.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:46:0)
[16:59:19.489] Timer tick, asking Buildbucket for the build status
[16:59:19.767] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:19.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:47:0) after 1m0s
[17:00:19.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:47:0)
[17:00:19.798] Timer tick, asking Buildbucket for the build status
[17:00:19.920] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:19.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:48:0) after 1m0s
[17:01:19.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:48:0)
[17:01:19.954] Timer tick, asking Buildbucket for the build status
[17:01:20.083] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:20.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:49:0) after 1m0s
[17:02:20.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:49:0)
[17:02:20.112] Timer tick, asking Buildbucket for the build status
[17:02:20.216] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:50:0) after 1m0s
[17:03:20.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:50:0)
[17:03:20.262] Timer tick, asking Buildbucket for the build status
[17:03:20.346] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:20.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:51:0) after 1m0s
[17:04:20.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:51:0)
[17:04:20.401] Timer tick, asking Buildbucket for the build status
[17:04:20.499] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:20.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:52:0) after 1m0s
[17:05:20.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:52:0)
[17:05:20.531] Timer tick, asking Buildbucket for the build status
[17:05:20.640] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:20.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:53:0) after 1m0s
[17:06:20.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:53:0)
[17:06:20.683] Timer tick, asking Buildbucket for the build status
[17:06:20.829] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:20.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:54:0) after 1m0s
[17:07:20.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:54:0)
[17:07:20.891] Timer tick, asking Buildbucket for the build status
[17:07:20.975] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:20.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:55:0) after 1m0s
[17:08:21.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:55:0)
[17:08:21.023] Timer tick, asking Buildbucket for the build status
[17:08:21.209] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:21.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:56:0) after 1m0s
[17:09:21.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:56:0)
[17:09:21.578] Timer tick, asking Buildbucket for the build status
[17:09:21.712] Build 8862553570657504224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:21.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027983633043049312:57:0) after 1m0s
[17:09:29.787] Received PubSub notification, asking Buildbucket for the build status
[17:09:30.074] Build 8862553570657504224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:09:30.074] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606410568882583",
"created_by": "project:chromium",
"created_ts": "1606407304097616",
"id": "8862553570657504224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\"}}",
"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\": \"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\", \"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-2gr3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15866, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"8420c5acaa568cacb7283116fce3ad283075166e\", \"got_dawn_revision\": \"71279dcde7cd1b718b137b9ed0384803f347a359\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\", \"got_revision_cp\": \"refs/heads/master@{#831365}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"0994d406d27080cbf0ba5c690553f5229dc0df6b\", \"got_v8_revision_cp\": \"refs/heads/8.9.57@{#1}\", \"got_webrtc_revision\": \"b7d89ca0d3ef9688152c35be2726f1eb29861d19\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32702}\", \"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\": \"3442ee6e38f2ad6edcd0b90046b684baf560aaf6\", \"swarm_hashes_refs/heads/master(at){#831365}_without_patch\": {\"absl_hardening_tests\": \"fe9c71320b40661e15dfaa8766917a2f93948b87\", \"accessibility_unittests\": \"89440ff8794094b9d43a6a63423ec272e22fd4dd\", \"angle_unittests\": \"6a2a9b1be32caa63418f74d6cfb929d371e70563\", \"app_shell_unittests\": \"876cdf9e0c67ffa570c58e1015916b5a72b4b202\", \"aura_unittests\": \"0140094dba977f90084160625488f2187b6e98b4\", \"base_unittests\": \"ac0fb231da359ccb949d3bab87d7bf71f55d17b0\", \"base_util_unittests\": \"a64d862733d7940273201b3493cb362bbbd1d821\", \"blink_common_unittests\": \"a91887fd6a78413bb05dfd876de4a5af8c8feff4\", \"blink_fuzzer_unittests\": \"5c903757d1df6ae483ac8db66012327bfbe912b8\", \"blink_heap_unittests\": \"fc4bda6f152489c0b139353a7d6a3eb7a03f78e0\", \"blink_platform_unittests\": \"a11fea2f52a87ca40bcdec879d6b022fb1ae4cfa\", \"blink_unittests\": \"c4e78e67bc1222e129870d94a64737158791a0f4\", \"boringssl_crypto_tests\": \"79c8c6448665944fc1b251b6d4a76b23d071d754\", \"boringssl_ssl_tests\": \"f35f036739e7f37cbc3af7cdb34d88573b6456a7\", \"browser_switcher_bho_unittests\": \"fe30cf9c4d9a55413dab0233f5206a5e14cf12d2\", \"browser_tests\": \"2da79b55f08c33a9148771a627c4d4eb127b4d97\", \"capture_unittests\": \"30bc2b7fc0f523470ff1d472430024c60fb0d35f\", \"cast_unittests\": \"ffd01e8680708213313e358d53ab3408033949d1\", \"cc_unittests\": \"289b5f2d89dcb51afbc79f5e75515af550d66c58\", \"chrome_app_unittests\": \"8ef37f76f7d68dbe5ff5f032c8f3cdf3972d3849\", \"chrome_cleaner_unittests\": \"0974ca2a9711027de8c95a726ece3480ae5c4e7d\", \"chrome_elf_unittests\": \"10fa30c81eb86aa036db9049c2a16a2448f3f02f\", \"chromedriver_unittests\": \"c3ab4604641b1d92abf1455181c4cf8d951354f2\", \"components_browsertests\": \"ca261b448511540ee1af36e4ba68a54da832626f\", \"components_unittests\": \"659abad747a424bd8729ad4d9e0bb0502627434d\", \"compositor_unittests\": \"b0ff861be4363c8e3f7f383019bf0bca8e934bbd\", \"content_browsertests\": \"b73874321a3b7093d9967c2f54dfa57ef91028fd\", \"content_unittests\": \"e937ec735344f0aeb2981eba08c306d525bec88c\", \"courgette_unittests\": \"bdbec3503b847734cf0c77dfc30483eac9e2e488\", \"crashpad_tests\": \"00f6d94f9152b6f6cd09dab93edd6565457cefe9\", \"cronet_tests\": \"882c18d482401d7bf2138fbf4d3a33c0cb69d339\", \"cronet_unittests\": \"b5c5ba1a7df65270671bbe8a2ca254230028974c\", \"crypto_unittests\": \"3effe8101f63a51922ce470afa5d81f0470d3ba7\", \"delayloads_unittests\": \"b3dfdcc546f85eec01dd901285a9085d079c4d7e\", \"device_unittests\": \"8728b38dfd08c0609896727cad0058e096eaea5b\", \"display_unittests\": \"157a94b57f25a4c538fccd4c5aceefcfcae6a010\", \"elevation_service_unittests\": \"5d5028360b2dfe8eebf8b0a1c10a792b9ca6af99\", \"events_unittests\": \"18d26e0d6a31c3142288c450fd694701cc5f11e7\", \"extensions_browsertests\": \"951d01dad2378c9198679922ca2c69c7ebab6ab8\", \"extensions_unittests\": \"edd3925d4d8ade96da588e17e5f0d3d70979e32b\", \"filesystem_service_unittests\": \"212e62a7deedf920ffa3aebed1d056ee2c748b6b\", \"gcm_unit_tests\": \"0c1b8e3342ae04c5fbf09307e29beaffa8844355\", \"gcp_unittests\": \"d9285f58279f680092af069629093230db504733\", \"gfx_unittests\": \"762be6a23f2cf43c87325320c96ea83bb8497a8b\", \"gin_unittests\": \"0fe8d53dc2a4aad9cfd7f6a2d6af804eb9a16a71\", \"google_apis_unittests\": \"95986837bcf56a38a4aacff400d1f4fd9a362d19\", \"gpu_unittests\": \"701f42c83908bed7bcbdd2b0a71b4a8aa9462a4d\", \"gwp_asan_unittests\": \"950af201bc0df496f5ce8aabcc4930f18b713209\", \"headless_browsertests\": \"25155b52704716827a5067be69c468603c7cc10b\", \"headless_unittests\": \"367538aef489f60516f348e46d0ca4fbd98a16f5\", \"install_static_unittests\": \"f4e5fcdf6a3410c5737acfa527c0985a165fc0aa\", \"installer_util_unittests\": \"59fdd3b335fe40c3bf72672abffdfb27916d9faf\", \"interactive_ui_tests\": \"7eea0e80f45bdec8aa2fb1ea62b99520426589b1\", \"ipc_tests\": \"1d44b22cf09364873ede81ec47287ace41f6d880\", \"jingle_unittests\": \"590ea3c72533f7bed0715c11ee68ea68f8cfce27\", \"latency_unittests\": \"4eee220ad71c3910babb9d92a9951e30c2df05c4\", \"libjingle_xmpp_unittests\": \"382d478074320bc60c06f431a8889bf647790906\", \"liburlpattern_unittests\": \"46d7912414e786df56cee9d25952283f8c83ba87\", \"media_blink_unittests\": \"cae7d65fc95b41efccc4ef4f1ffd4e2700c9a8ff\", \"media_unittests\": \"02cf62642e2b86450e06e02971b8b602daa84831\", \"message_center_unittests\": \"26420866d2156061d21dbd30c51ec9e775aaaac9\", \"midi_unittests\": \"f6ce177e762b65756b58de71fda0bc7f92c30b42\", \"mojo_core_unittests\": \"1c515ffcb6c6f7665e32f21be0b7edade935d182\", \"mojo_unittests\": \"e77b2a7ef1f2e639157600eaeacffcb7884d19a5\", \"nacl_loader_unittests\": \"3aa5e13a30e710d4780f3039dd8c84a89a2696a9\", \"native_theme_unittests\": \"b299c732ec89e5a5cba46916864a01bd8c3a13c7\", \"net_unittests\": \"8941a41b8e4dfdb056eb04e25d9e8305fd628068\", \"notification_helper_unittests\": \"c9069b2dfde9188f9c537d93c495180f3674d782\", \"pdf_unittests\": \"137f23b9d5431278ec78f574e0e7b60cf53809eb\", \"perfetto_unittests\": \"73fa5d5636ae403d4fd7d3a576e2782ec3f747ce\", \"ppapi_unittests\": \"bf1999b131a107aa108497e6d372741948566c77\", \"printing_unittests\": \"44f5b7eddf7023ad8074c0bf9d9bedde50ecac47\", \"remoting_unittests\": \"5ca291025e59273d32a4ca75478f66080cad865a\", \"sbox_integration_tests\": \"ed1b3d1d87f0d40e38cf8ba5e8d5314e14d43384\", \"sbox_unittests\": \"9e26c8b28b907df78651d97933c8244727e85627\", \"sbox_validation_tests\": \"6601222e9232d91980d5667181a18cf0bfdae464\", \"service_manager_unittests\": \"c6ec9ba3c93e58fcbccb514ad0bf26f18a7b3756\", \"services_unittests\": \"49cb1279b81bb6222f9fd9180c9ee226f65bb468\", \"setup_unittests\": \"74ae1ae51f11e1cf4baab7d2cdea9868c16d7091\", \"shell_dialogs_unittests\": \"903878ddb0415c9fdaab6b0c6d3bfa9c60d6961d\", \"skia_unittests\": \"53ab9251cf53879d51bc511b72df7eca68ecb5a4\", \"snapshot_unittests\": \"3655ab0ca63d824881508dc45f3a66caf4fae3ed\", \"sql_unittests\": \"32f414f61a155321f6c8ada8a61c3e44cf6ccc0c\", \"storage_unittests\": \"e6c9efe27a96aab41c6fd1ac64fcbf2c50fd4faa\", \"sync_integration_tests\": \"c5bfe3a5bf7af36eac56b0ab35a4a23a0ecc3efb\", \"traffic_annotation_auditor_unittests\": \"02c08776bf7b03a346e98b4f269a2c1830a0658c\", \"ui_base_unittests\": \"3ab71e34ba2c92ae990c4925e4a457fddde24d2c\", \"ui_touch_selection_unittests\": \"e7d6927f4d2453870b251263ecf0ada55ba5af9f\", \"unit_tests\": \"5a84c154178b4d07843bc8fd31e523400e543878\", \"updater_tests\": \"8ab2c08998e338aeacccd4c5e0ebb7a0e2128b6e\", \"url_unittests\": \"facb78916eb9b388274d864c04dd8d01f070d91d\", \"views_unittests\": \"94c3cd7165a8fbd09b67dee8d6c59d7931b3256a\", \"viz_unittests\": \"06963da5a23167e5b705340b64ca722d9909fd8e\", \"vr_common_unittests\": \"5a426a704cc0e2140b0ccb5c3651cb8814006110\", \"vr_pixeltests\": \"91a0ee8d008be56bc77cf208e4200e9c9361af66\", \"weblayer_browsertests\": \"7a024e2a7ae725ccd968704de634a5a2318dbabf\", \"weblayer_unittests\": \"c5a151558a8179ad72e46829e3024739a05cf20d\", \"wm_unittests\": \"ef43f04411494464c8eaad257b7b44748d284e7a\", \"wtf_unittests\": \"c83da810b62d203e7c24d9bf04f784ffe999b38c\", \"zlib_unittests\": \"9955d5adcdfd6f3c24aca3a206a9a03b4a5bfde0\", \"zucchini_unittests\": \"fb9c6cc9dfe627e3a4c8ad757ab401fdbdc6adae\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-2gr3\"], \"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\": [\"5640-d0eb544\"], \"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": "1606407306669888",
"status": "COMPLETED",
"status_changed_ts": "1606410569186159",
"tags": [
"build_address:luci.chromium.ci/win-asan/15866",
"builder:win-asan",
"buildset:commit/git/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3442ee6e38f2ad6edcd0b90046b684baf560aaf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027983633043049312",
"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/8862553570657504224/+/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:501e27c24e217810",
"user_agent:luci-scheduler"
],
"updated_ts": "1606410569192508",
"url": "https://ci.chromium.org/b/8862553570657504224",
"utcnow_ts": "1606410570056367"
}
}
[17:09:30.074] Invocation finished in 54m27.636616684s with status SUCCEEDED