[05:49:35.007] New invocation is queued and will start shortly
[05:49:36.129] Starting the invocation (attempt 1)
[05:49:36.165] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:49:36.166] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:49:36.166] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028294774328111264",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c788f0a735e72e6dac77f05b599be338a3f5f6d0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028294774328111264",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"gitiles_ref:refs/heads/master"
]
}
[05:49:37.427] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606110576294955",
"id": "8862864712310402096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c788f0a735e72e6dac77f05b599be338a3f5f6d0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606110577240132",
"tags": [
"build_address:luci.chromium.ci/win-asan/15796",
"builder:win-asan",
"buildset:commit/git/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028294774328111264",
"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/8862864712310402096/+/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": "1606110577240378",
"url": "https://ci.chromium.org/b/8862864712310402096",
"utcnow_ts": "1606110577419257"
}
}
[05:49:37.427] Task URL: https://ci.chromium.org/b/8862864712310402096
[05:49:37.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:2:0) after 1m0s
[05:49:42.440] Received PubSub notification, asking Buildbucket for the build status
[05:49:42.520] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:37.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:2:0)
[05:50:37.437] Timer tick, asking Buildbucket for the build status
[05:50:37.551] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:37.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:4:0) after 1m0s
[05:51:37.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:4:0)
[05:51:37.562] Timer tick, asking Buildbucket for the build status
[05:51:37.673] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:37.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:5:0) after 1m0s
[05:52:37.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:5:0)
[05:52:37.697] Timer tick, asking Buildbucket for the build status
[05:52:37.813] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:37.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:6:0) after 1m0s
[05:53:37.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:6:0)
[05:53:37.886] Timer tick, asking Buildbucket for the build status
[05:53:38.209] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:38.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:7:0) after 1m0s
[05:54:38.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:7:0)
[05:54:38.235] Timer tick, asking Buildbucket for the build status
[05:54:38.420] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:38.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:8:0) after 1m0s
[05:55:38.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:8:0)
[05:55:38.438] Timer tick, asking Buildbucket for the build status
[05:55:38.538] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:38.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:9:0) after 1m0s
[05:56:38.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:9:0)
[05:56:38.565] Timer tick, asking Buildbucket for the build status
[05:56:38.635] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:38.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:10:0) after 1m0s
[05:57:38.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:10:0)
[05:57:38.705] Timer tick, asking Buildbucket for the build status
[05:57:40.224] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:40.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:11:0) after 1m0s
[05:58:40.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:11:0)
[05:58:40.397] Timer tick, asking Buildbucket for the build status
[05:58:40.799] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:40.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:12:0) after 1m0s
[05:59:40.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:12:0)
[05:59:40.896] Timer tick, asking Buildbucket for the build status
[05:59:41.065] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:13:0) after 1m0s
[06:00:41.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:13:0)
[06:00:41.178] Timer tick, asking Buildbucket for the build status
[06:00:41.274] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:41.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:14:0) after 1m0s
[06:01:48.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:14:0)
[06:01:48.710] Timer tick, asking Buildbucket for the build status
[06:01:48.780] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:48.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:15:0) after 1m0s
[06:02:49.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:15:0)
[06:02:49.041] Timer tick, asking Buildbucket for the build status
[06:02:49.249] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:49.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:16:0) after 1m0s
[06:03:49.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:16:0)
[06:03:49.395] Timer tick, asking Buildbucket for the build status
[06:03:49.527] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:49.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:17:0) after 1m0s
[06:04:49.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:17:0)
[06:04:49.575] Timer tick, asking Buildbucket for the build status
[06:04:49.672] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:49.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:18:0) after 1m0s
[06:05:49.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:18:0)
[06:05:49.889] Timer tick, asking Buildbucket for the build status
[06:05:50.216] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:50.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:19:0) after 1m0s
[06:06:50.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:19:0)
[06:06:50.363] Timer tick, asking Buildbucket for the build status
[06:06:50.497] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:50.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:20:0) after 1m0s
[06:07:50.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:20:0)
[06:07:50.717] Timer tick, asking Buildbucket for the build status
[06:07:50.841] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:50.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:21:0) after 1m0s
[06:08:50.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:21:0)
[06:08:50.894] Timer tick, asking Buildbucket for the build status
[06:08:50.996] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:50.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:22:0) after 1m0s
[06:09:51.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:22:0)
[06:09:51.114] Timer tick, asking Buildbucket for the build status
[06:09:51.741] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:51.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:23:0) after 1m0s
[06:10:51.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:23:0)
[06:10:51.845] Timer tick, asking Buildbucket for the build status
[06:10:52.013] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:24:0) after 1m0s
[06:11:52.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:24:0)
[06:11:52.104] Timer tick, asking Buildbucket for the build status
[06:11:52.213] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:52.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:25:0) after 1m0s
[06:12:52.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:25:0)
[06:12:52.248] Timer tick, asking Buildbucket for the build status
[06:12:52.352] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:52.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:26:0) after 1m0s
[06:13:52.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:26:0)
[06:13:52.406] Timer tick, asking Buildbucket for the build status
[06:13:52.524] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:52.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:27:0) after 1m0s
[06:14:52.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:27:0)
[06:14:52.571] Timer tick, asking Buildbucket for the build status
[06:14:52.999] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:52.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:28:0) after 1m0s
[06:15:53.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:28:0)
[06:15:53.050] Timer tick, asking Buildbucket for the build status
[06:15:53.325] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:53.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:29:0) after 1m0s
[06:16:54.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:29:0)
[06:16:54.349] Timer tick, asking Buildbucket for the build status
[06:16:54.439] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:54.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:30:0) after 1m0s
[06:17:55.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:30:0)
[06:17:55.049] Timer tick, asking Buildbucket for the build status
[06:17:55.180] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:55.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:31:0) after 1m0s
[06:18:56.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:31:0)
[06:18:56.031] Timer tick, asking Buildbucket for the build status
[06:18:56.122] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:56.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:32:0) after 1m0s
[06:19:56.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:32:0)
[06:19:56.247] Timer tick, asking Buildbucket for the build status
[06:19:56.357] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:56.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:33:0) after 1m0s
[06:20:56.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:33:0)
[06:20:56.437] Timer tick, asking Buildbucket for the build status
[06:20:56.518] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:56.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:34:0) after 1m0s
[06:21:56.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:34:0)
[06:21:56.594] Timer tick, asking Buildbucket for the build status
[06:21:56.809] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:56.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:35:0) after 1m0s
[06:22:56.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:35:0)
[06:22:56.880] Timer tick, asking Buildbucket for the build status
[06:22:56.971] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:56.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:36:0) after 1m0s
[06:23:57.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:36:0)
[06:23:57.073] Timer tick, asking Buildbucket for the build status
[06:23:57.169] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:57.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:37:0) after 1m0s
[06:24:57.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:37:0)
[06:24:57.281] Timer tick, asking Buildbucket for the build status
[06:24:57.461] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:57.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:38:0) after 1m0s
[06:25:57.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:38:0)
[06:25:57.664] Timer tick, asking Buildbucket for the build status
[06:25:58.691] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:58.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:39:0) after 1m0s
[06:26:58.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:39:0)
[06:26:58.752] Timer tick, asking Buildbucket for the build status
[06:26:58.994] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:40:0) after 1m0s
[06:27:59.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:40:0)
[06:27:59.043] Timer tick, asking Buildbucket for the build status
[06:27:59.142] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:59.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:41:0) after 1m0s
[06:28:59.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:41:0)
[06:28:59.192] Timer tick, asking Buildbucket for the build status
[06:28:59.492] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:59.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:42:0) after 1m0s
[06:29:59.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:42:0)
[06:29:59.603] Timer tick, asking Buildbucket for the build status
[06:29:59.738] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:59.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:43:0) after 1m0s
[06:30:59.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:43:0)
[06:30:59.805] Timer tick, asking Buildbucket for the build status
[06:30:59.916] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:59.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:44:0) after 1m0s
[06:32:00.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:44:0)
[06:32:00.034] Timer tick, asking Buildbucket for the build status
[06:32:00.288] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:00.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:45:0) after 1m0s
[06:33:00.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:45:0)
[06:33:00.309] Timer tick, asking Buildbucket for the build status
[06:33:00.478] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:00.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:46:0) after 1m0s
[06:34:00.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:46:0)
[06:34:00.899] Timer tick, asking Buildbucket for the build status
[06:34:01.325] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:01.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:47:0) after 1m0s
[06:35:01.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:47:0)
[06:35:01.575] Timer tick, asking Buildbucket for the build status
[06:35:01.741] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:01.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:48:0) after 1m0s
[06:36:02.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:48:0)
[06:36:02.123] Timer tick, asking Buildbucket for the build status
[06:36:02.286] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:02.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:49:0) after 1m0s
[06:37:02.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:49:0)
[06:37:02.295] Timer tick, asking Buildbucket for the build status
[06:37:02.412] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:02.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:50:0) after 1m0s
[06:38:02.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:50:0)
[06:38:02.655] Timer tick, asking Buildbucket for the build status
[06:38:02.730] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:02.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:51:0) after 1m0s
[06:39:02.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:51:0)
[06:39:02.741] Timer tick, asking Buildbucket for the build status
[06:39:03.064] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:52:0) after 1m0s
[06:40:03.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:52:0)
[06:40:03.113] Timer tick, asking Buildbucket for the build status
[06:40:03.233] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:03.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:53:0) after 1m0s
[06:41:03.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:53:0)
[06:41:03.261] Timer tick, asking Buildbucket for the build status
[06:41:03.529] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:03.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:54:0) after 1m0s
[06:42:03.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:54:0)
[06:42:03.588] Timer tick, asking Buildbucket for the build status
[06:42:03.753] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:03.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:55:0) after 1m0s
[06:43:03.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:55:0)
[06:43:03.848] Timer tick, asking Buildbucket for the build status
[06:43:03.992] Build 8862864712310402096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:03.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028294774328111264:56:0) after 1m0s
[06:43:19.616] Received PubSub notification, asking Buildbucket for the build status
[06:43:19.900] Build 8862864712310402096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:43:19.900] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606113797994520",
"created_by": "project:chromium",
"created_ts": "1606110576294955",
"id": "8862864712310402096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c788f0a735e72e6dac77f05b599be338a3f5f6d0\"}}",
"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\": \"c788f0a735e72e6dac77f05b599be338a3f5f6d0\", \"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-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15796, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"ebf890fcf9f3e297dcb0736b31bce3e63d63c4d7\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"c788f0a735e72e6dac77f05b599be338a3f5f6d0\", \"got_revision_cp\": \"refs/heads/master@{#830092}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"805e8b5987e5fd07adbe998f0c21f84cb20468b8\", \"got_v8_revision_cp\": \"refs/heads/8.9.32@{#1}\", \"got_webrtc_revision\": \"c5fd745672798221b71611a92c33ab5f2bf04a3b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32663}\", \"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\": \"c788f0a735e72e6dac77f05b599be338a3f5f6d0\", \"swarm_hashes_refs/heads/master(at){#830092}_without_patch\": {\"absl_hardening_tests\": \"7133664e0bba59ef9dbc62ab522207bed43e79ba\", \"accessibility_unittests\": \"9daaad1a6bd6c9a8bdc1fa7affb6c5ce13a3385d\", \"angle_unittests\": \"214b2216f78d619f7108a164229c4e28c4b6e18f\", \"app_shell_unittests\": \"35b0d99be5494b07090039f466f614cb93d67f72\", \"aura_unittests\": \"8d288c6f048d999a67bf21c0f692ca21a40a31c8\", \"base_unittests\": \"fd5bebdcf9bfeb18e5164b30c16aaf3047938529\", \"base_util_unittests\": \"344de9314a2631ab8be109999fa4cc68f9552ebd\", \"blink_common_unittests\": \"0ee8b0b07d29c0966fdb540453833b07afced7df\", \"blink_fuzzer_unittests\": \"331e9892a7585aa9c02922dab4ef9b95f031d689\", \"blink_heap_unittests\": \"9a4810ff4083c2870901f5b46506849967c7980f\", \"blink_platform_unittests\": \"025c2bf60c4ea7a71fa2f583f7714cb16f80c016\", \"blink_unittests\": \"2374934c44ce069feca2928f74c2c8180d0daa56\", \"boringssl_crypto_tests\": \"d5f3623ef8694834303a3dd2cd58e0c3305b9d37\", \"boringssl_ssl_tests\": \"eb3c0436a4e9746bfde45d17c61295325c04749c\", \"browser_switcher_bho_unittests\": \"66265532d2d6ccf45178c65f1cd0f6bbc9b1be3d\", \"browser_tests\": \"7c0355c75c43c0d63cce07d1e5ec8792cb873c24\", \"capture_unittests\": \"e22d28fe8275b85ba16bfabcbdf09bb21cc4fb9b\", \"cast_unittests\": \"d81d2ed45d2779e41020121ed8ef8cc78f316109\", \"cc_unittests\": \"7d08fad84c3cf75b32d10347f459e63508e588ac\", \"chrome_app_unittests\": \"91f2282078a6c42fe35003b3d6bd071420526b7b\", \"chrome_cleaner_unittests\": \"39a645e109fe0fd31d7da428d4015239bb432f61\", \"chrome_elf_unittests\": \"092b262d21adb8391c7ac521d8bc9bf4a970613f\", \"chromedriver_unittests\": \"55f301dda955c1903119111bb5c30f35f47e6675\", \"components_browsertests\": \"07d5d64a3c8eb2b65e92443662b4ecb348d33893\", \"components_unittests\": \"8f513e8d37b5e0bdff51179b551a7b30d46d5af7\", \"compositor_unittests\": \"0db9039b927ec53fe8eaca1a7281fcf2f46d9242\", \"content_browsertests\": \"3608c71a29013ef7ae56e342be948e36f5c7bd6e\", \"content_unittests\": \"eeaca06fbcbafbc939f41c8ab293f7de7fc11925\", \"courgette_unittests\": \"a6a6dc1a7cfdb363ab169eb4eae4872089470e7a\", \"crashpad_tests\": \"6d197fa1bceaf0439d565d497df6de49a4079feb\", \"cronet_tests\": \"c11d07317ceb4c8bc4b86ddf8b53b939c1cda395\", \"cronet_unittests\": \"0dba188a34236968013b4393fee9a3ab326770ca\", \"crypto_unittests\": \"c050f8cd1a1f699cad5bd5c190f5c2b0101961d4\", \"delayloads_unittests\": \"583642115faabd80dfe8ee7862423dd0adac81dc\", \"device_unittests\": \"b741c4b8372f6efa18eeb6f3f860d275924a1a10\", \"display_unittests\": \"f98b3613cb5490c99a734477d1a1bdd71697700a\", \"elevation_service_unittests\": \"75567a9ddbf6865e26bc70695e975a71307d2d84\", \"events_unittests\": \"5b8cad19b190be2d1e6f8423d48001a4eb359a60\", \"extensions_browsertests\": \"55deb9358733f72f7edbbc4b53e8713ac753e2e4\", \"extensions_unittests\": \"ef6548aa3c733a57e7eab52917b918e8dcccc212\", \"filesystem_service_unittests\": \"81b0a8a49da7058c1ac46484bee80e7f4a5e0a11\", \"gcm_unit_tests\": \"02bf9d8ce196981fdd8e142a838ab90219c18f34\", \"gcp_unittests\": \"bc10cae9c073489054670b7e0ea42d7f62a578d0\", \"gfx_unittests\": \"940639f6cb5a04bb4c3b25f81d47d91603168018\", \"gin_unittests\": \"433713d8273b9c7a7156a13e4cffda970bb77df5\", \"google_apis_unittests\": \"eab873b8836da6d8aa21cf504b6f54ef9103b1e1\", \"gpu_unittests\": \"394494c98f520cf58ed52bba998d3c4461c3cfd2\", \"gwp_asan_unittests\": \"0d488f5943840e7270100ba1d78124d677612f34\", \"headless_browsertests\": \"3c76af20199c8d620d8e401710d9f37b4278f7a4\", \"headless_unittests\": \"f95e5c0a770e7a65c51aedf1097a8ab5250ed961\", \"install_static_unittests\": \"204e1cb8f5306d49dadf8df5e98944c7e0f0a1b2\", \"installer_util_unittests\": \"779389f1f97fa6eb7cdd62c12a282b516a53f882\", \"interactive_ui_tests\": \"eb8d06b1c4782737981a7b55d41bc4a2bcabeb7b\", \"ipc_tests\": \"28e20bc8f473ebb6c17af88124dcba435f2b43b6\", \"jingle_unittests\": \"6dd5a129f1c8f41dec714b6d967ba2885f82648b\", \"latency_unittests\": \"7912eb2600191ef40e0313856b1f3568d2a279d0\", \"libjingle_xmpp_unittests\": \"8af0aebaa4bffad9292c37d22dd1c588cc9eb06f\", \"liburlpattern_unittests\": \"d3f364e7548ede5e4a94cec4f4ff81d2e6974636\", \"media_blink_unittests\": \"77dcf26814ba8a4feb05d5b96f278efdd8119f9a\", \"media_unittests\": \"04861f9ff6ddbda419db37f623aa517c79310841\", \"message_center_unittests\": \"d4e0da2029e84e9fd4da56feec4bbdb9c5d48132\", \"midi_unittests\": \"51834081c04bf20c7f29906b92f24677b36719ae\", \"mojo_core_unittests\": \"418bc7419aeb0471549b594bf74e7529b78ea273\", \"mojo_unittests\": \"32cc7e0dfc7e2664b93aa73a4a4902d220e8b1ac\", \"nacl_loader_unittests\": \"5c0345afdddb56a3f9cfb2e45ef10ab3fba702c5\", \"native_theme_unittests\": \"10a02b67d6dcd78f04ece40550f6e7dd5377ea1e\", \"net_unittests\": \"66842429028e5ee0a3d90bb021a75f9f01688907\", \"notification_helper_unittests\": \"fd6842b0a1b0a77404ad18e1186e99e0d2398cca\", \"pdf_unittests\": \"67d9ffcbd23d6cff4d8cf49e4f3cf8137749aa13\", \"perfetto_unittests\": \"23383c9d1a5279a22941a2f22f8b4af5d7ff59d5\", \"ppapi_unittests\": \"9760ff7c44eb5b2bc411822765f19311e6b0d285\", \"printing_unittests\": \"b315e3884b55d70c6fe1f804b4e02b6bd8c71963\", \"remoting_unittests\": \"860231e652712a50a89eccad39d762bb8dbd0056\", \"sbox_integration_tests\": \"eb18b34957ac6297a76823e0704d644a9965f864\", \"sbox_unittests\": \"60e3d4c608f4c71289d54d0af81ecf9187ead33e\", \"sbox_validation_tests\": \"4c53728763f090bded01b1dbe2d987e54e3787b0\", \"service_manager_unittests\": \"02c738f0a5a24ef378d5bd8652ce5cac7b483639\", \"services_unittests\": \"32e523e89939a4f72c5e70ef1945fa03be568284\", \"setup_unittests\": \"f5814e4631aecb1bdb76d65c1c8097c42b5fc381\", \"shell_dialogs_unittests\": \"08e56d59de060459b63e95902986768dd7a8b6a4\", \"skia_unittests\": \"ed423e51b88e9dbd6b30f11991aff0863220d5c6\", \"snapshot_unittests\": \"5077fc9b257dfe44717a9d6be9bfa28a55182aad\", \"sql_unittests\": \"710023e51e8225a0fb3e56f73664cb24faf17f9f\", \"storage_unittests\": \"2c8ba188d58c290e6e8c3a1b4e8f21dd321db0e5\", \"sync_integration_tests\": \"3f4486e394aade76e276547bf98b76bb77a1004a\", \"traffic_annotation_auditor_unittests\": \"9cb25f2945c22e7cbfd4233b6d58dca4da2d7a02\", \"ui_base_unittests\": \"933c491be328bcb3e76d2873ff8d5a65ab095864\", \"ui_touch_selection_unittests\": \"f57eca872c9366cfe83f2a05153aca80aae4869f\", \"unit_tests\": \"300efd12295df4a7a58a455b38f15f8c6d924992\", \"updater_tests\": \"d36f8aec576246b7262fe8196449fa5c9f92542d\", \"url_unittests\": \"f49470ebcb9f6e4072a1e2ae88fad9038a7fe233\", \"views_unittests\": \"7b2e5c43a556b35a08888152a57301812d41b3b6\", \"viz_unittests\": \"fc508b4fa8e86ccbad3e766c4eed2fb1285e9379\", \"vr_common_unittests\": \"d267b8292068ff3b39892325a37b7d9df034f1dc\", \"vr_pixeltests\": \"726457a6c9cf394b54ebe80509caf39471ae0fb2\", \"weblayer_browsertests\": \"717cd3d865b745b2c6c6f3a42bdedbe406ef087a\", \"weblayer_unittests\": \"ed5fd2b972b7f49d06cdc70150c1f02462023860\", \"wm_unittests\": \"ee8d43321caa81d1d98dfa3803d26e726815d240\", \"wtf_unittests\": \"4cab2a6513691ccbf5c4373d6a77ffe924bd0a8f\", \"zlib_unittests\": \"52b9dcc1d72bacbe9ab53a4c856b08b695d3870f\", \"zucchini_unittests\": \"d4ce940b38f20152328f14dc84b866bd66cf7943\"}}, \"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-3-xoby\"], \"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\": [\"5637-4d8b832\"], \"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": "1606110581717071",
"status": "COMPLETED",
"status_changed_ts": "1606113798413350",
"tags": [
"build_address:luci.chromium.ci/win-asan/15796",
"builder:win-asan",
"buildset:commit/git/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c788f0a735e72e6dac77f05b599be338a3f5f6d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028294774328111264",
"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/8862864712310402096/+/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:500c780c867ce710",
"user_agent:luci-scheduler"
],
"updated_ts": "1606113798418054",
"url": "https://ci.chromium.org/b/8862864712310402096",
"utcnow_ts": "1606113799881218"
}
}
[06:43:19.900] Invocation finished in 53m44.913951462s with status SUCCEEDED