[02:37:04.365] New invocation is queued and will start shortly
[02:37:05.444] Starting the invocation (attempt 1)
[02:37:05.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:37:05.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:37:05.510] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027582110319002640",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c36c5078c41bd1a9e2455d747d69ac1703d977d3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027582110319002640",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"gitiles_ref:refs/heads/master"
]
}
[02:37:06.567] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606790225686357",
"id": "8862152048269222336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c36c5078c41bd1a9e2455d747d69ac1703d977d3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606790226392590",
"tags": [
"build_address:luci.chromium.ci/win-asan/15958",
"builder:win-asan",
"buildset:commit/git/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027582110319002640",
"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/8862152048269222336/+/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": "1606790226393032",
"url": "https://ci.chromium.org/b/8862152048269222336",
"utcnow_ts": "1606790226559247"
}
}
[02:37:06.567] Task URL: https://ci.chromium.org/b/8862152048269222336
[02:37:06.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:2:0) after 1m0s
[02:37:10.425] Received PubSub notification, asking Buildbucket for the build status
[02:37:11.024] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:06.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:2:0)
[02:38:06.673] Timer tick, asking Buildbucket for the build status
[02:38:06.746] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:06.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:4:0) after 1m0s
[02:39:06.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:4:0)
[02:39:06.890] Timer tick, asking Buildbucket for the build status
[02:39:07.175] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:07.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:5:0) after 1m0s
[02:40:07.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:5:0)
[02:40:07.251] Timer tick, asking Buildbucket for the build status
[02:40:07.550] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:6:0) after 1m0s
[02:41:07.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:6:0)
[02:41:07.751] Timer tick, asking Buildbucket for the build status
[02:41:07.873] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:07.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:7:0) after 1m0s
[02:42:07.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:7:0)
[02:42:07.992] Timer tick, asking Buildbucket for the build status
[02:42:08.459] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:08.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:8:0) after 1m0s
[02:43:08.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:8:0)
[02:43:08.554] Timer tick, asking Buildbucket for the build status
[02:43:08.627] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:08.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:9:0) after 1m0s
[02:44:08.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:9:0)
[02:44:08.679] Timer tick, asking Buildbucket for the build status
[02:44:08.770] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:08.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:10:0) after 1m0s
[02:45:08.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:10:0)
[02:45:08.853] Timer tick, asking Buildbucket for the build status
[02:45:08.925] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:08.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:11:0) after 1m0s
[02:46:08.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:11:0)
[02:46:08.937] Timer tick, asking Buildbucket for the build status
[02:46:09.025] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:09.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:12:0) after 1m0s
[02:47:09.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:12:0)
[02:47:09.113] Timer tick, asking Buildbucket for the build status
[02:47:09.229] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:09.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:13:0) after 1m0s
[02:48:09.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:13:0)
[02:48:09.262] Timer tick, asking Buildbucket for the build status
[02:48:09.365] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:09.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:14:0) after 1m0s
[02:49:09.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:14:0)
[02:49:09.445] Timer tick, asking Buildbucket for the build status
[02:49:09.713] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:09.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:15:0) after 1m0s
[02:50:09.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:15:0)
[02:50:09.951] Timer tick, asking Buildbucket for the build status
[02:50:10.096] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:10.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:16:0) after 1m0s
[02:51:10.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:16:0)
[02:51:10.162] Timer tick, asking Buildbucket for the build status
[02:51:10.235] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:10.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:17:0) after 1m0s
[02:52:10.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:17:0)
[02:52:10.360] Timer tick, asking Buildbucket for the build status
[02:52:10.593] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:18:0) after 1m0s
[02:53:10.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:18:0)
[02:53:10.692] Timer tick, asking Buildbucket for the build status
[02:53:10.849] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:19:0) after 1m0s
[02:54:10.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:19:0)
[02:54:10.953] Timer tick, asking Buildbucket for the build status
[02:54:11.113] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:11.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:20:0) after 1m0s
[02:55:11.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:20:0)
[02:55:11.166] Timer tick, asking Buildbucket for the build status
[02:55:11.396] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:11.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:21:0) after 1m0s
[02:56:11.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:21:0)
[02:56:11.456] Timer tick, asking Buildbucket for the build status
[02:56:11.761] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:22:0) after 1m0s
[02:57:11.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:22:0)
[02:57:11.805] Timer tick, asking Buildbucket for the build status
[02:57:11.979] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:11.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:23:0) after 1m0s
[02:58:12.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:23:0)
[02:58:12.053] Timer tick, asking Buildbucket for the build status
[02:58:12.327] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:12.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:24:0) after 1m0s
[02:59:12.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:24:0)
[02:59:12.378] Timer tick, asking Buildbucket for the build status
[02:59:12.532] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:12.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:25:0) after 1m0s
[03:00:12.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:25:0)
[03:00:12.778] Timer tick, asking Buildbucket for the build status
[03:00:13.558] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:13.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:26:0) after 1m0s
[03:01:13.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:26:0)
[03:01:13.642] Timer tick, asking Buildbucket for the build status
[03:01:16.012] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:16.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:27:0) after 1m0s
[03:02:16.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:27:0)
[03:02:16.241] Timer tick, asking Buildbucket for the build status
[03:02:16.427] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:16.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:28:0) after 1m0s
[03:03:16.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:28:0)
[03:03:16.485] Timer tick, asking Buildbucket for the build status
[03:03:16.698] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:16.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:29:0) after 1m0s
[03:04:16.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:29:0)
[03:04:16.687] Timer tick, asking Buildbucket for the build status
[03:04:16.837] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:16.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:30:0) after 1m0s
[03:05:16.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:30:0)
[03:05:16.855] Timer tick, asking Buildbucket for the build status
[03:05:16.982] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:16.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:31:0) after 1m0s
[03:06:17.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:31:0)
[03:06:17.066] Timer tick, asking Buildbucket for the build status
[03:06:17.413] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:17.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:32:0) after 1m0s
[03:07:17.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:32:0)
[03:07:17.504] Timer tick, asking Buildbucket for the build status
[03:07:17.759] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:17.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:33:0) after 1m0s
[03:08:17.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:33:0)
[03:08:17.879] Timer tick, asking Buildbucket for the build status
[03:08:18.002] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:18.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:34:0) after 1m0s
[03:09:17.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:34:0)
[03:09:17.994] Timer tick, asking Buildbucket for the build status
[03:09:18.357] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:18.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:35:0) after 1m0s
[03:10:18.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:35:0)
[03:10:18.360] Timer tick, asking Buildbucket for the build status
[03:10:18.441] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:18.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:36:0) after 1m0s
[03:11:18.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:36:0)
[03:11:18.656] Timer tick, asking Buildbucket for the build status
[03:11:18.761] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:18.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:37:0) after 1m0s
[03:12:18.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:37:0)
[03:12:18.854] Timer tick, asking Buildbucket for the build status
[03:12:19.049] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:19.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:38:0) after 1m0s
[03:13:19.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:38:0)
[03:13:19.187] Timer tick, asking Buildbucket for the build status
[03:13:19.371] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:19.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:39:0) after 1m0s
[03:14:19.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:39:0)
[03:14:19.480] Timer tick, asking Buildbucket for the build status
[03:14:19.655] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:19.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:40:0) after 1m0s
[03:15:19.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:40:0)
[03:15:19.676] Timer tick, asking Buildbucket for the build status
[03:15:20.278] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:20.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:41:0) after 1m0s
[03:16:20.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:41:0)
[03:16:20.353] Timer tick, asking Buildbucket for the build status
[03:16:20.463] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:20.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:42:0) after 1m0s
[03:17:20.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:42:0)
[03:17:20.520] Timer tick, asking Buildbucket for the build status
[03:17:20.667] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:43:0) after 1m0s
[03:18:20.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:43:0)
[03:18:20.691] Timer tick, asking Buildbucket for the build status
[03:18:20.836] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:44:0) after 1m0s
[03:19:20.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:44:0)
[03:19:20.869] Timer tick, asking Buildbucket for the build status
[03:19:21.272] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:21.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:45:0) after 1m0s
[03:20:21.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:45:0)
[03:20:21.360] Timer tick, asking Buildbucket for the build status
[03:20:22.530] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:22.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:46:0) after 1m0s
[03:21:22.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:46:0)
[03:21:22.664] Timer tick, asking Buildbucket for the build status
[03:21:22.831] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:22.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:47:0) after 1m0s
[03:22:22.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:47:0)
[03:22:22.952] Timer tick, asking Buildbucket for the build status
[03:22:25.146] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:25.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:48:0) after 1m0s
[03:23:25.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:48:0)
[03:23:25.301] Timer tick, asking Buildbucket for the build status
[03:23:25.395] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:25.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:49:0) after 1m0s
[03:24:25.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:49:0)
[03:24:25.385] Timer tick, asking Buildbucket for the build status
[03:24:25.624] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:25.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:50:0) after 1m0s
[03:25:25.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:50:0)
[03:25:25.812] Timer tick, asking Buildbucket for the build status
[03:25:25.965] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:25.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:51:0) after 1m0s
[03:26:26.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:51:0)
[03:26:26.008] Timer tick, asking Buildbucket for the build status
[03:26:26.108] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:26.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:52:0) after 1m0s
[03:27:26.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:52:0)
[03:27:26.777] Timer tick, asking Buildbucket for the build status
[03:27:27.132] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:27.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:53:0) after 1m0s
[03:28:27.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:53:0)
[03:28:27.127] Timer tick, asking Buildbucket for the build status
[03:28:27.212] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:27.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:54:0) after 1m0s
[03:29:27.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:54:0)
[03:29:27.241] Timer tick, asking Buildbucket for the build status
[03:29:27.585] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:27.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:55:0) after 1m0s
[03:30:27.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:55:0)
[03:30:27.689] Timer tick, asking Buildbucket for the build status
[03:30:27.798] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:27.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:56:0) after 1m0s
[03:31:27.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:56:0)
[03:31:27.957] Timer tick, asking Buildbucket for the build status
[03:31:28.205] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:57:0) after 1m0s
[03:32:28.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:57:0)
[03:32:28.227] Timer tick, asking Buildbucket for the build status
[03:32:28.500] Build 8862152048269222336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027582110319002640:58:0) after 1m0s
[03:32:29.952] Received PubSub notification, asking Buildbucket for the build status
[03:32:30.204] Build 8862152048269222336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:32:30.204] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606793549243647",
"created_by": "project:chromium",
"created_ts": "1606790225686357",
"id": "8862152048269222336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c36c5078c41bd1a9e2455d747d69ac1703d977d3\"}}",
"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\": \"c36c5078c41bd1a9e2455d747d69ac1703d977d3\", \"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-10-eupo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15958, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"3f564fc76ea8a556d628517b055af85c27a60bc3\", \"got_dawn_revision\": \"6a7095d8c75373e363d52faed3c74f517ca7311f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"c36c5078c41bd1a9e2455d747d69ac1703d977d3\", \"got_revision_cp\": \"refs/heads/master@{#832169}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"1eadcfb3be99c2eca944774a78c4e3c16132958b\", \"got_v8_revision\": \"f1e2bbd80f2e2eacf7e043e18ee55f0b8cf22171\", \"got_v8_revision_cp\": \"refs/heads/8.9.71@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"c36c5078c41bd1a9e2455d747d69ac1703d977d3\", \"swarm_hashes_refs/heads/master(at){#832169}_without_patch\": {\"absl_hardening_tests\": \"a46c58e7b26fe663bbb3e2a6410a5ef074e84ccf\", \"accessibility_unittests\": \"27526a4966338577af5a52932f165da5a28bb137\", \"angle_unittests\": \"8132173424c841de9c8e04d816a9065676c17d85\", \"app_shell_unittests\": \"8ddbaf4ad12554585cd5701dab2dae84be94ee6b\", \"aura_unittests\": \"5df3adda29cb74f3add083c30e4d7bef23e19ccb\", \"base_unittests\": \"69a4d76ae6b6de862b223f455e2f72196423d387\", \"base_util_unittests\": \"a472347e2c8cc48a19df07ba192f583c3d34acb9\", \"blink_common_unittests\": \"9acb2115e2b5180f1c551446a6f5a3c5cf88cbdf\", \"blink_fuzzer_unittests\": \"ac1221180e8720332857946ab5090695a32b6ab6\", \"blink_heap_unittests\": \"6dad09060a5deb99fcd69b15d6f444d01fe8038e\", \"blink_platform_unittests\": \"56c6bc6dc98cdf55d978cc0e54f40307b766f2b8\", \"blink_unittests\": \"c6aca860fbc58ef18426dd336ed618900a18733f\", \"boringssl_crypto_tests\": \"10dc9f721d0a41d098a266dff9f1196da6bfbe40\", \"boringssl_ssl_tests\": \"e903ed21b1df04649ee90cde37d4d360e41b2de8\", \"browser_switcher_bho_unittests\": \"3bb341c7d6c92978a824d8d7bee0e4023d7aaf2b\", \"browser_tests\": \"11a4edca617b9ea52976873230ccac3daaeb7480\", \"capture_unittests\": \"f45deeb9762dbf3e2ecd851231a4469b8816345a\", \"cast_unittests\": \"21dcb9e7e8f0543f2835d637349f1c658b16eb49\", \"cc_unittests\": \"d903c023cda8bb1d9e62d346fd34580439823415\", \"chrome_app_unittests\": \"b5f7474c36e310d695cfdbf6d130039f00fd0688\", \"chrome_cleaner_unittests\": \"b36702d265f5f65bfbd3add6d165a8b9cd42c396\", \"chrome_elf_unittests\": \"2e992f2d0c688fe7229309e3d318a4080c02cc7f\", \"chromedriver_unittests\": \"a7669c7ead8bcd91c24967705b85af2db0987517\", \"components_browsertests\": \"5b49bc1ce724ea651f7e3ac413d8fb2e6c913809\", \"components_unittests\": \"8f6e42b6740da6eebf79178b8571f304382aa4e2\", \"compositor_unittests\": \"2f23eaab4c79a429e31d53f4146b1439ba7854bc\", \"content_browsertests\": \"4c63822531e2c300d6f8a48829efb7d8b60511cb\", \"content_unittests\": \"99e4d4f45e8a41da9f5ba93f1f7eb17c6b5f9b09\", \"courgette_unittests\": \"1e170551d86feb2e0efad232e058852d9efe48f1\", \"crashpad_tests\": \"213c52b6839941607cf9942d69583e0d8e185867\", \"cronet_tests\": \"c5920cdc89cbd5a53769ebfd0dce635c52582c7d\", \"cronet_unittests\": \"342f98ab812cf48793891d775d0a1ac450c097c4\", \"crypto_unittests\": \"47b69d2f240f9c4368214f5f5fe0d3f8323832c3\", \"delayloads_unittests\": \"10f359d44814494a4db563ef98e118bb7e7fe2ea\", \"device_unittests\": \"ae19e357b89e21762d303bb44f4b2acacbae8b0c\", \"display_unittests\": \"87804a11502ef596084a1f94efaa2b62b17adfaa\", \"elevation_service_unittests\": \"a6b5fe49406b3c5d284f14384bef5f1053ec3d04\", \"events_unittests\": \"3c254cc73846993a06bd37ec26e758de16034bcd\", \"extensions_browsertests\": \"6501259e7f3f63ee6ce2f3b92ca6c28abacc3b70\", \"extensions_unittests\": \"ef10f023670974c1988d0b62abcf521361fec076\", \"filesystem_service_unittests\": \"a6938d1d2c6bf5133bf2c51400d73305f0ffba73\", \"gcm_unit_tests\": \"b6879c25fe0952f0150735c38b1b487f9177fb56\", \"gcp_unittests\": \"b9c5445f6b02a299549998bac949b15a7fc99dcd\", \"gfx_unittests\": \"d7157de18d9b13b12ce1306817a7ededbf63c9eb\", \"gin_unittests\": \"6d370391af84fe31aefb1dc03731fba2ad203e80\", \"google_apis_unittests\": \"af3a21cf9bda1993c6771ca289b7a3a425385221\", \"gpu_unittests\": \"dd6af08ac2b5b4fa6328aafa21c01cb845b0b21d\", \"gwp_asan_unittests\": \"56d0b45c50fa0d46d9f677c5f25b5c0755b90dfd\", \"headless_browsertests\": \"9d89d18c6f2b223195fd8004843c2587255edca4\", \"headless_unittests\": \"e2f3ab1cf2bbaa421826e1be1d9c7c9a62034eb0\", \"install_static_unittests\": \"38a509e5e34dc4ea1b71ad202e00f9084cbc17ce\", \"installer_util_unittests\": \"461831023781858bd98b4490eb945ab9d8f90e63\", \"interactive_ui_tests\": \"3eb6650337aceb586b8cc367d00e4112288a934c\", \"ipc_tests\": \"ed1403204cd35cd79cd3651b716cb7ccacc8ef88\", \"jingle_unittests\": \"af9bc424e19ae4abe83ab912f4a18acecf8ffe5e\", \"latency_unittests\": \"2e120deed22bb2d23dc6605b7aa2adc7e6b31b8a\", \"libjingle_xmpp_unittests\": \"54598cd9aba5652bdeb5c1075fce2d2de1ca56cf\", \"liburlpattern_unittests\": \"02391d14c3f1e446c4498413c74793b28a8adeeb\", \"media_blink_unittests\": \"5b40fa145907f4e70929c54244b6bd0f340b2f91\", \"media_unittests\": \"f712e746c7b595f4f4e78c1f9701c5c338900f7f\", \"message_center_unittests\": \"2d245e5c0e13c9d57405b60f02ded6923d070481\", \"midi_unittests\": \"e085e0bad533aa5a67892c5c1c336849fe6c3d80\", \"mojo_core_unittests\": \"7ac3ccc5af263cc0c4c87af78c5a40e4c55233a4\", \"mojo_unittests\": \"e0c9b28bb8424c2c683d162671a02ebf044cb3a6\", \"nacl_loader_unittests\": \"73be21617d69c9a17d53b8344382e3b289d59498\", \"native_theme_unittests\": \"467cf407454d2df7588bc10300ffee53a1ed94bf\", \"net_unittests\": \"3266321ba8a4bdfffde82f8f83dec1b54afd4760\", \"notification_helper_unittests\": \"bec447f125533c27b2128e59ad3c7f375db77d1a\", \"pdf_unittests\": \"7b0115f25da3a1e5a36fece1c7a36cf31208008e\", \"perfetto_unittests\": \"0df840b2d7a5de929fca4475c830c19759d23cef\", \"ppapi_unittests\": \"0be85c0a85aa9bde2098131a62eda4bfa5a01ea3\", \"printing_unittests\": \"74c42a0ed55eca9cce0924becc7979338a8120af\", \"remoting_unittests\": \"8984a1c97f0b05ac43e8c559ed636bce2ff0addc\", \"sbox_integration_tests\": \"78a9f4c4cc5fb5b22863fa3215c1bec74237716e\", \"sbox_unittests\": \"125359b15996a40d69272f867c90ca02d9256c8d\", \"sbox_validation_tests\": \"1e8ae836887e65b4623560e35fb343b069be7032\", \"service_manager_unittests\": \"0cce442be03bf75d453a0a9c7fabe408ebdb9f05\", \"services_unittests\": \"300a95bfbe66929a943d5b957217364416ef9d46\", \"setup_unittests\": \"9e5325f563812a6a66179e998bc6211bb6447049\", \"shell_dialogs_unittests\": \"c19cc341412bff7f3d6898fdb1f96defb3a904d7\", \"skia_unittests\": \"164779562225d4df4237ec5e7867baec577033eb\", \"snapshot_unittests\": \"023cab1a4c1750ab4b3db4ea9d2c80cb5d94a843\", \"sql_unittests\": \"c1fa1e5246c440472ffaf34f3ee04b60f1571672\", \"storage_unittests\": \"d640f26744df6235c8213ad73064497fff2d2b53\", \"sync_integration_tests\": \"d0ea4f378669b50f5254d8978c465c7e15572666\", \"traffic_annotation_auditor_unittests\": \"e5151861ca02f7c53b07109757816a98d655711c\", \"ui_base_unittests\": \"6a3aba28d1febcb1369c38f68610e81a117158d3\", \"ui_touch_selection_unittests\": \"45fcad5431bb345e5176f650641bc981170996db\", \"unit_tests\": \"ae27ae9b482f6a4489b51e82eb1ff92b74551cc1\", \"updater_tests\": \"27ff2c249dac675085ea897104621c084e74711a\", \"url_unittests\": \"f4d17a269366b20d6b490fa8ec5b315692cf95bd\", \"views_unittests\": \"c0ccc048bacb3ed4d43b64519b6dd15ca41d5d33\", \"viz_unittests\": \"48bc0c003f2fbd8edd3ecb2989d030095a56dc10\", \"vr_common_unittests\": \"c815af6102433b46cf916497a39c9762fa5a33c0\", \"vr_pixeltests\": \"f7bf00a9ab6e0a2c02be7eb3acdbda8404ece176\", \"weblayer_browsertests\": \"d7f373f06e9dacc7dc3c81f3c371704529ec0416\", \"weblayer_unittests\": \"78bc371a3a62ee60779dbe20b4400886c1c88225\", \"wm_unittests\": \"f819a6118100bc200a8b7bf4120cee231e0c3a18\", \"wtf_unittests\": \"049357734096aba41efa6053649a147202dd7a2b\", \"zlib_unittests\": \"b453f0ff79bfc1c1b58daedfca05ea854c768f97\", \"zucchini_unittests\": \"ff097ad93a53e093c7e6e6b1e71317a8309c3648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-10-eupo\"], \"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": "1606790229340741",
"status": "COMPLETED",
"status_changed_ts": "1606793549511713",
"tags": [
"build_address:luci.chromium.ci/win-asan/15958",
"builder:win-asan",
"buildset:commit/git/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c36c5078c41bd1a9e2455d747d69ac1703d977d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027582110319002640",
"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/8862152048269222336/+/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:5034faad74ef4610",
"user_agent:luci-scheduler"
],
"updated_ts": "1606793549519230",
"url": "https://ci.chromium.org/b/8862152048269222336",
"utcnow_ts": "1606793550187767"
}
}
[03:32:30.204] Invocation finished in 55m25.856016368s with status SUCCEEDED