[16:11:59.133] New invocation is queued and will start shortly
[16:12:00.510] Starting the invocation (attempt 1)
[16:12:00.577] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:12:00.577] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:12:00.577] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030248749436284592",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"af6207c5d56f6c4bb23bc5c060e415931aa516d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030248749436284592",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"gitiles_ref:refs/heads/master"
]
}
[16:12:01.702] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604247120688324",
"id": "8864818687135248096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af6207c5d56f6c4bb23bc5c060e415931aa516d2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604247121163161",
"tags": [
"build_address:luci.chromium.ci/win-asan/15396",
"builder:win-asan",
"buildset:commit/git/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030248749436284592",
"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/8864818687135248096/+/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": "1604247121163308",
"url": "https://ci.chromium.org/b/8864818687135248096",
"utcnow_ts": "1604247121687855"
}
}
[16:12:01.702] Task URL: https://ci.chromium.org/b/8864818687135248096
[16:12:01.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:2:0) after 1m0s
[16:12:06.002] Received PubSub notification, asking Buildbucket for the build status
[16:12:06.174] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:01.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:2:0)
[16:13:01.754] Timer tick, asking Buildbucket for the build status
[16:13:01.864] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:01.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:4:0) after 1m0s
[16:14:02.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:4:0)
[16:14:02.065] Timer tick, asking Buildbucket for the build status
[16:14:02.487] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:02.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:5:0) after 1m0s
[16:15:02.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:5:0)
[16:15:02.593] Timer tick, asking Buildbucket for the build status
[16:15:02.716] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:02.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:6:0) after 1m0s
[16:16:02.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:6:0)
[16:16:02.940] Timer tick, asking Buildbucket for the build status
[16:16:03.057] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:03.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:7:0) after 1m0s
[16:17:03.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:7:0)
[16:17:03.058] Timer tick, asking Buildbucket for the build status
[16:17:03.435] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:03.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:8:0) after 1m0s
[16:18:03.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:8:0)
[16:18:03.487] Timer tick, asking Buildbucket for the build status
[16:18:03.563] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:9:0) after 1m0s
[16:19:03.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:9:0)
[16:19:03.837] Timer tick, asking Buildbucket for the build status
[16:19:03.929] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:03.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:10:0) after 1m0s
[16:20:04.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:10:0)
[16:20:04.159] Timer tick, asking Buildbucket for the build status
[16:20:04.560] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:04.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:11:0) after 1m0s
[16:21:04.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:11:0)
[16:21:04.724] Timer tick, asking Buildbucket for the build status
[16:21:04.919] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:04.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:12:0) after 1m0s
[16:22:05.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:12:0)
[16:22:05.007] Timer tick, asking Buildbucket for the build status
[16:22:05.300] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:05.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:13:0) after 1m0s
[16:23:05.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:13:0)
[16:23:05.320] Timer tick, asking Buildbucket for the build status
[16:23:05.789] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:05.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:14:0) after 1m0s
[16:24:05.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:14:0)
[16:24:05.821] Timer tick, asking Buildbucket for the build status
[16:24:05.951] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:05.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:15:0) after 1m0s
[16:25:05.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:15:0)
[16:25:05.979] Timer tick, asking Buildbucket for the build status
[16:25:06.083] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:06.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:16:0) after 1m0s
[16:26:06.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:16:0)
[16:26:06.107] Timer tick, asking Buildbucket for the build status
[16:26:06.678] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:06.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:17:0) after 1m0s
[16:27:06.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:17:0)
[16:27:06.769] Timer tick, asking Buildbucket for the build status
[16:27:07.288] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:07.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:18:0) after 1m0s
[16:28:07.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:18:0)
[16:28:07.346] Timer tick, asking Buildbucket for the build status
[16:28:07.478] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:07.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:19:0) after 1m0s
[16:29:07.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:19:0)
[16:29:07.591] Timer tick, asking Buildbucket for the build status
[16:29:07.799] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:20:0) after 1m0s
[16:30:07.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:20:0)
[16:30:07.880] Timer tick, asking Buildbucket for the build status
[16:30:08.001] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:08.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:21:0) after 1m0s
[16:31:08.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:21:0)
[16:31:08.107] Timer tick, asking Buildbucket for the build status
[16:31:08.235] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:08.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:22:0) after 1m0s
[16:32:08.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:22:0)
[16:32:08.459] Timer tick, asking Buildbucket for the build status
[16:32:08.580] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:08.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:23:0) after 1m0s
[16:33:08.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:23:0)
[16:33:08.589] Timer tick, asking Buildbucket for the build status
[16:33:08.656] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:08.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:24:0) after 1m0s
[16:34:08.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:24:0)
[16:34:08.673] Timer tick, asking Buildbucket for the build status
[16:34:09.347] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:09.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:25:0) after 1m0s
[16:35:09.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:25:0)
[16:35:09.470] Timer tick, asking Buildbucket for the build status
[16:35:09.628] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:09.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:26:0) after 1m0s
[16:36:09.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:26:0)
[16:36:09.820] Timer tick, asking Buildbucket for the build status
[16:36:09.930] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:09.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:27:0) after 1m0s
[16:37:09.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:27:0)
[16:37:09.907] Timer tick, asking Buildbucket for the build status
[16:37:10.095] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:10.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:28:0) after 1m0s
[16:38:10.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:28:0)
[16:38:10.178] Timer tick, asking Buildbucket for the build status
[16:38:10.318] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:10.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:29:0) after 1m0s
[16:39:10.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:29:0)
[16:39:10.372] Timer tick, asking Buildbucket for the build status
[16:39:10.984] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:10.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:30:0) after 1m0s
[16:40:11.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:30:0)
[16:40:11.030] Timer tick, asking Buildbucket for the build status
[16:40:11.138] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:11.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:31:0) after 1m0s
[16:41:11.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:31:0)
[16:41:11.168] Timer tick, asking Buildbucket for the build status
[16:41:11.300] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:11.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:32:0) after 1m0s
[16:42:11.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:32:0)
[16:42:11.375] Timer tick, asking Buildbucket for the build status
[16:42:11.551] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:11.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:33:0) after 1m0s
[16:43:11.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:33:0)
[16:43:11.676] Timer tick, asking Buildbucket for the build status
[16:43:11.869] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:11.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:34:0) after 1m0s
[16:44:11.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:34:0)
[16:44:11.856] Timer tick, asking Buildbucket for the build status
[16:44:11.981] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:11.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:35:0) after 1m0s
[16:45:12.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:35:0)
[16:45:12.059] Timer tick, asking Buildbucket for the build status
[16:45:12.278] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:12.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:36:0) after 1m0s
[16:46:12.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:36:0)
[16:46:12.340] Timer tick, asking Buildbucket for the build status
[16:46:12.525] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:12.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:37:0) after 1m0s
[16:47:12.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:37:0)
[16:47:12.730] Timer tick, asking Buildbucket for the build status
[16:47:13.065] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:13.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:38:0) after 1m0s
[16:48:13.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:38:0)
[16:48:13.169] Timer tick, asking Buildbucket for the build status
[16:48:13.277] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:13.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:39:0) after 1m0s
[16:49:13.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:39:0)
[16:49:13.299] Timer tick, asking Buildbucket for the build status
[16:49:13.366] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:13.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:40:0) after 1m0s
[16:50:13.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:40:0)
[16:50:13.497] Timer tick, asking Buildbucket for the build status
[16:50:13.987] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:13.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:41:0) after 1m0s
[16:51:14.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:41:0)
[16:51:14.053] Timer tick, asking Buildbucket for the build status
[16:51:14.177] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:14.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:42:0) after 1m0s
[16:52:14.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:42:0)
[16:52:14.196] Timer tick, asking Buildbucket for the build status
[16:52:14.309] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:14.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:43:0) after 1m0s
[16:53:14.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:43:0)
[16:53:14.392] Timer tick, asking Buildbucket for the build status
[16:53:14.489] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:14.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:44:0) after 1m0s
[16:54:14.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:44:0)
[16:54:14.510] Timer tick, asking Buildbucket for the build status
[16:54:14.658] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:14.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:45:0) after 1m0s
[16:55:14.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:45:0)
[16:55:14.769] Timer tick, asking Buildbucket for the build status
[16:55:14.841] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:14.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:46:0) after 1m0s
[16:56:14.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:46:0)
[16:56:14.889] Timer tick, asking Buildbucket for the build status
[16:56:15.382] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:15.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:47:0) after 1m0s
[16:57:15.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:47:0)
[16:57:15.412] Timer tick, asking Buildbucket for the build status
[16:57:15.544] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:15.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:48:0) after 1m0s
[16:58:15.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:48:0)
[16:58:15.619] Timer tick, asking Buildbucket for the build status
[16:58:15.801] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:15.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:49:0) after 1m0s
[16:59:15.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:49:0)
[16:59:15.865] Timer tick, asking Buildbucket for the build status
[16:59:16.008] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:16.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:50:0) after 1m0s
[17:00:16.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:50:0)
[17:00:16.067] Timer tick, asking Buildbucket for the build status
[17:00:16.287] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:16.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:51:0) after 1m0s
[17:01:16.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:51:0)
[17:01:16.323] Timer tick, asking Buildbucket for the build status
[17:01:16.436] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:16.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:52:0) after 1m0s
[17:02:16.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:52:0)
[17:02:16.462] Timer tick, asking Buildbucket for the build status
[17:02:16.815] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:16.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:53:0) after 1m0s
[17:03:16.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:53:0)
[17:03:16.866] Timer tick, asking Buildbucket for the build status
[17:03:17.039] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:17.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:54:0) after 1m0s
[17:04:17.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:54:0)
[17:04:17.125] Timer tick, asking Buildbucket for the build status
[17:04:17.197] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:17.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:55:0) after 1m0s
[17:05:17.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:55:0)
[17:05:17.345] Timer tick, asking Buildbucket for the build status
[17:05:17.419] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:17.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:56:0) after 1m0s
[17:06:17.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:56:0)
[17:06:17.485] Timer tick, asking Buildbucket for the build status
[17:06:17.609] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:17.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:57:0) after 1m0s
[17:07:17.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:57:0)
[17:07:17.645] Timer tick, asking Buildbucket for the build status
[17:07:17.790] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:17.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:58:0) after 1m0s
[17:08:17.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:58:0)
[17:08:17.815] Timer tick, asking Buildbucket for the build status
[17:08:18.072] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:18.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:59:0) after 1m0s
[17:09:18.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:59:0)
[17:09:18.242] Timer tick, asking Buildbucket for the build status
[17:09:18.522] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:18.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:60:0) after 1m0s
[17:10:18.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:60:0)
[17:10:18.632] Timer tick, asking Buildbucket for the build status
[17:10:18.915] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:18.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:61:0) after 1m0s
[17:11:18.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:61:0)
[17:11:18.947] Timer tick, asking Buildbucket for the build status
[17:11:19.014] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:19.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:62:0) after 1m0s
[17:12:19.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:62:0)
[17:12:19.126] Timer tick, asking Buildbucket for the build status
[17:12:19.504] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:19.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:63:0) after 1m0s
[17:13:19.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:63:0)
[17:13:19.526] Timer tick, asking Buildbucket for the build status
[17:13:19.590] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:19.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:64:0) after 1m0s
[17:14:19.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:64:0)
[17:14:19.616] Timer tick, asking Buildbucket for the build status
[17:14:19.936] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:19.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:65:0) after 1m0s
[17:15:19.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:65:0)
[17:15:19.970] Timer tick, asking Buildbucket for the build status
[17:15:20.307] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:20.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:66:0) after 1m0s
[17:16:20.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:66:0)
[17:16:20.340] Timer tick, asking Buildbucket for the build status
[17:16:20.638] Build 8864818687135248096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:20.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030248749436284592:67:0) after 1m0s
[17:16:24.240] Received PubSub notification, asking Buildbucket for the build status
[17:16:24.408] Build 8864818687135248096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:16:24.408] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604250982207569",
"created_by": "project:chromium",
"created_ts": "1604247120688324",
"id": "8864818687135248096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af6207c5d56f6c4bb23bc5c060e415931aa516d2\"}}",
"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\": \"af6207c5d56f6c4bb23bc5c060e415931aa516d2\", \"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-5-387x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15396, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"9c66a0925dbc6c03f898a852702d504fd465c133\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"af6207c5d56f6c4bb23bc5c060e415931aa516d2\", \"got_revision_cp\": \"refs/heads/master@{#823021}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af6207c5d56f6c4bb23bc5c060e415931aa516d2\", \"swarm_hashes_refs/heads/master(at){#823021}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"7ccff270fcefca221891719d41cf59a0ca1b962e\", \"angle_unittests\": \"42980150a330f2f9bb656d96a31db32631f60932\", \"app_shell_unittests\": \"711dcbd651f621202774c09112fae50d0b8cde48\", \"aura_unittests\": \"b3b3d1ae81903f5f6a1347c706a51842b76c5907\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"83f88b1e1811d9467ced6b49e70afa617902fca3\", \"blink_fuzzer_unittests\": \"04935b65ae33ae59302e48efcbefa4eb76fda3f4\", \"blink_heap_unittests\": \"e4d2495036f0284648efd2259d23251c530f4f59\", \"blink_platform_unittests\": \"0e9bc2101ffe4b24eb3234049c51b6334387085e\", \"blink_unittests\": \"c8e83659f248bb3c9b74cb107db643eb27d566f8\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"561e042f5c5e0982b53bc8d137f1964ea231bed1\", \"capture_unittests\": \"84033674b2eafbbe3b685545b404487db11bde2d\", \"cast_unittests\": \"47926be68f4c5abcb66c2c81c0f861f11d03756a\", \"cc_unittests\": \"19d1af76653ee69fb0a0973dd7b93e22a0cf10f1\", \"chrome_app_unittests\": \"7cd53a439ea172ef1e86a27c1d4897b604e21fd3\", \"chrome_cleaner_unittests\": \"3f537172fb6446f3f452691d486cc1c973469521\", \"chrome_elf_unittests\": \"eacf1e1deca9c992953e3f040cfc5ed8f8b83da2\", \"chromedriver_unittests\": \"7792419ed90073b760d1cd0b4c63b8f6192c6606\", \"components_browsertests\": \"7e06f01dc476e6e51005aaec6420c9c8eef56f5d\", \"components_unittests\": \"61ead82e5dd169b0d710bdae28a7b78bbbaab78b\", \"compositor_unittests\": \"0fcf6a519fa2d2b433272403cfa75cdc6d54dbd3\", \"content_browsertests\": \"f091176d6eebac82717ce6f0d00bad851811c55a\", \"content_unittests\": \"261deb19d98bf832e7265087da22a3d413c6a1c9\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"9a2ce8a697496ad3fcd0c7357d08f4796320c41c\", \"cronet_unittests\": \"4a148f87d1453cc757af61d98b3cc30c5d6cc41c\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"57a05c8e545e94537287fc380bfd0ad454217e09\", \"device_unittests\": \"ba41d6b8a235e6c7132ce7d4983535f559005767\", \"display_unittests\": \"48e36a521c81286942c8f880fef43a49e6ba4777\", \"elevation_service_unittests\": \"b23dacae202f610cad7c03762096b2053364e8bc\", \"events_unittests\": \"d9b4cfc5de5941154372ae90a032b29a24113a91\", \"extensions_browsertests\": \"d43439ac6735915ac9744ed3ec4c76e0da9079bd\", \"extensions_unittests\": \"65ac9c53f5ae5ae91b623eb19fd419b82e200a02\", \"filesystem_service_unittests\": \"056340320eca3f9739cad04f49dae9cf335c66b7\", \"gcm_unit_tests\": \"8e28098e895246f8d09dd61723d49786dea497f2\", \"gcp_unittests\": \"0a56c52010902e15036919f24776f237a71ebe47\", \"gfx_unittests\": \"ea78ac04ab5f4208f55e011e0efbc9b58573c2f3\", \"gin_unittests\": \"423efac58365bece43917c4d0cfdb4ab20899643\", \"google_apis_unittests\": \"4b0f71e27d1ae8ba2e61d134f610ff60c20ad715\", \"gpu_unittests\": \"1940332d3200f965279d90717f9a5697b53e643b\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"69e9c03f6d56344cf9cb7292e3e5006bbc0fa5ef\", \"headless_unittests\": \"e4c41da9a699c1ebfe0e394fe67726aaba87b77d\", \"install_static_unittests\": \"69d2cffbb392c81bd6141e9d66645a01b93f66bb\", \"installer_util_unittests\": \"6071420b49115013684b51b20f4078f22e622e33\", \"interactive_ui_tests\": \"fe0fb5bd1d35ca40767832a221cf93a13044a2ce\", \"ipc_tests\": \"b85f3053e75d959540a1f23ad2fcac415ffdea01\", \"jingle_unittests\": \"2ab56f48a9f1081932b5ceece05ede913230c135\", \"latency_unittests\": \"a52eaa20aa6a4718d7010eebb63f37c0c133cb5a\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"b9b30964abab0180014a80b4626d18e42887e50d\", \"media_unittests\": \"d552948e751e78cc01e083f5431067635c3860ee\", \"message_center_unittests\": \"12b23c5d9486f3628fc8bd02b1a67806b5be5eff\", \"midi_unittests\": \"a3a4f1a223c28d6734ed71a02aa0d935ec9aa952\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"a9c2bbac2c2d832ae3c49baed062453062faf916\", \"nacl_loader_unittests\": \"a05fa3ffb553d70307ff7edf98931f9ccbdbadc2\", \"native_theme_unittests\": \"5733d8be35da31eb4d371c5520cb706e8e0bb722\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"a06fc183a4bfe3dc8232b8941fe83d56e476e71d\", \"pdf_unittests\": \"b4bc77e145cfa1d552ce638db7a67e6b7a909806\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"8bc87e67349ef0a15a288301dc4a96cb4158e2c9\", \"printing_unittests\": \"81bcf54d507ef6d9cb057fe4b834c2183aafb095\", \"remoting_unittests\": \"56c848d966ff0620ac7cb764e114d5e712aca967\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"22dd36c766fe5919023ebe9bfc921c86201b310c\", \"services_unittests\": \"a6a066165e4efa78d9d39dcb31a142a1bd553566\", \"setup_unittests\": \"ad9276c5f80cd9268c307f26c9b3d96c111085d4\", \"shell_dialogs_unittests\": \"5da1f14f91c8117794a63382f2a89ce4db721a19\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"ec5a1fe1a54c876f4d3274449e2cad2ab195cfb1\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"eccb69bd9aa0e81878318ccd3c7ede06b18c8263\", \"sync_integration_tests\": \"42991b591384739a757756d16be5a5f3d33269df\", \"traffic_annotation_auditor_unittests\": \"ebd284495324c2bff70aaa79502b39da6769d3c0\", \"ui_base_unittests\": \"fa57b072128d4b0aecfad2b20a1fb4003a27f46e\", \"ui_touch_selection_unittests\": \"7a0d517041a9e09a9d3e510b113cd25700ff5ad9\", \"unit_tests\": \"5060aa9f8fc4c7a018ca5f86f4965f80f88c9cdf\", \"updater_tests\": \"48c6c8e066b86ebf52107f29748eb9e34600813e\", \"url_unittests\": \"acb7805b970e463382afbcbc7aa13c8b644b97cf\", \"views_unittests\": \"3e5656581253a06f369d2446ad68d0fddf6ed37b\", \"viz_unittests\": \"2f9afa338ff37120baf1ce9348327c73e0b58bca\", \"vr_common_unittests\": \"e023059c33c3ed0b09a699c19f62ed8bd075e3c2\", \"vr_pixeltests\": \"2257e3004e10dc38bf6257cde3ab94943876b643\", \"weblayer_browsertests\": \"d3e6f90ab101d64de236c7078a742978a2bcea97\", \"weblayer_unittests\": \"6c950b197ef07fc01c30d2339e580e479cd14a54\", \"wm_unittests\": \"a6d2c3175e03650d4c3969da1b6df7fdeaa77c91\", \"wtf_unittests\": \"009975168cf3284749e2887facb7c4971e403c31\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"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-5-387x\"], \"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\": [\"5614-d862eaa\"], \"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": "1604247123385707",
"status": "COMPLETED",
"status_changed_ts": "1604250982644390",
"tags": [
"build_address:luci.chromium.ci/win-asan/15396",
"builder:win-asan",
"buildset:commit/git/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af6207c5d56f6c4bb23bc5c060e415931aa516d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030248749436284592",
"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/8864818687135248096/+/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:4f9d65f754403610",
"user_agent:luci-scheduler"
],
"updated_ts": "1604250982649885",
"url": "https://ci.chromium.org/b/8864818687135248096",
"utcnow_ts": "1604250984398650"
}
}
[17:16:24.408] Invocation finished in 1h4m25.298776059s with status SUCCEEDED