[22:00:18.989] New invocation is queued and will start shortly
[22:00:20.296] Starting the invocation (attempt 1)
[22:00:20.331] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:00:20.331] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:00:20.331] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020170571075641376",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f93836f1098104c481510db19a1b5dae91659ef8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020170571075641376",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f93836f1098104c481510db19a1b5dae91659ef8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f93836f1098104c481510db19a1b5dae91659ef8",
"gitiles_ref:refs/heads/master"
]
}
[22:00:21.503] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613858420430730",
"id": "8854740508897182592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f93836f1098104c481510db19a1b5dae91659ef8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613858421215898",
"tags": [
"build_address:luci.chromium.ci/win-asan/17449",
"builder:win-asan",
"buildset:commit/git/f93836f1098104c481510db19a1b5dae91659ef8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f93836f1098104c481510db19a1b5dae91659ef8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020170571075641376",
"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/8854740508897182592/+/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": "1613858421216810",
"url": "https://ci.chromium.org/b/8854740508897182592",
"utcnow_ts": "1613858421488751"
}
}
[22:00:21.503] Task URL: https://ci.chromium.org/b/8854740508897182592
[22:00:21.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:2:0) after 1m0s
[22:00:24.359] Received PubSub notification, asking Buildbucket for the build status
[22:00:24.462] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:21.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:2:0)
[22:01:21.517] Timer tick, asking Buildbucket for the build status
[22:01:21.586] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:21.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:4:0) after 1m0s
[22:02:21.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:4:0)
[22:02:21.758] Timer tick, asking Buildbucket for the build status
[22:02:21.901] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:21.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:5:0) after 1m0s
[22:03:22.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:5:0)
[22:03:22.022] Timer tick, asking Buildbucket for the build status
[22:03:22.225] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:22.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:6:0) after 1m0s
[22:04:22.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:6:0)
[22:04:22.291] Timer tick, asking Buildbucket for the build status
[22:04:22.410] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:22.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:7:0) after 1m0s
[22:05:22.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:7:0)
[22:05:22.396] Timer tick, asking Buildbucket for the build status
[22:05:22.490] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:22.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:8:0) after 1m0s
[22:06:22.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:8:0)
[22:06:22.957] Timer tick, asking Buildbucket for the build status
[22:06:23.036] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:23.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:9:0) after 1m0s
[22:07:23.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:9:0)
[22:07:23.120] Timer tick, asking Buildbucket for the build status
[22:07:23.194] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:23.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:10:0) after 1m0s
[22:08:23.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:10:0)
[22:08:23.211] Timer tick, asking Buildbucket for the build status
[22:08:23.291] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:23.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:11:0) after 1m0s
[22:09:23.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:11:0)
[22:09:23.307] Timer tick, asking Buildbucket for the build status
[22:09:23.409] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:23.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:12:0) after 1m0s
[22:10:23.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:12:0)
[22:10:23.457] Timer tick, asking Buildbucket for the build status
[22:10:23.555] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:23.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:13:0) after 1m0s
[22:11:23.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:13:0)
[22:11:23.572] Timer tick, asking Buildbucket for the build status
[22:11:23.823] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:23.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:14:0) after 1m0s
[22:12:23.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:14:0)
[22:12:23.842] Timer tick, asking Buildbucket for the build status
[22:12:23.910] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:23.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:15:0) after 1m0s
[22:13:23.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:15:0)
[22:13:23.928] Timer tick, asking Buildbucket for the build status
[22:13:24.097] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:16:0) after 1m0s
[22:14:24.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:16:0)
[22:14:24.141] Timer tick, asking Buildbucket for the build status
[22:14:24.303] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:24.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:17:0) after 1m0s
[22:15:24.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:17:0)
[22:15:24.304] Timer tick, asking Buildbucket for the build status
[22:15:24.740] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:24.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:18:0) after 1m0s
[22:16:24.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:18:0)
[22:16:24.803] Timer tick, asking Buildbucket for the build status
[22:16:24.906] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:24.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:19:0) after 1m0s
[22:17:24.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:19:0)
[22:17:24.954] Timer tick, asking Buildbucket for the build status
[22:17:25.031] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:25.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:20:0) after 1m0s
[22:18:25.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:20:0)
[22:18:25.095] Timer tick, asking Buildbucket for the build status
[22:18:25.167] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:25.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:21:0) after 1m0s
[22:19:25.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:21:0)
[22:19:25.311] Timer tick, asking Buildbucket for the build status
[22:19:25.446] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:25.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:22:0) after 1m0s
[22:20:25.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:22:0)
[22:20:25.494] Timer tick, asking Buildbucket for the build status
[22:20:25.624] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:25.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:23:0) after 1m0s
[22:21:25.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:23:0)
[22:21:25.720] Timer tick, asking Buildbucket for the build status
[22:21:25.804] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:25.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:24:0) after 1m0s
[22:22:25.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:24:0)
[22:22:25.925] Timer tick, asking Buildbucket for the build status
[22:22:26.003] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:26.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:25:0) after 1m0s
[22:23:26.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:25:0)
[22:23:26.089] Timer tick, asking Buildbucket for the build status
[22:23:26.255] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:26.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:26:0) after 1m0s
[22:24:26.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:26:0)
[22:24:26.280] Timer tick, asking Buildbucket for the build status
[22:24:26.352] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:26.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:27:0) after 1m0s
[22:25:26.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:27:0)
[22:25:26.405] Timer tick, asking Buildbucket for the build status
[22:25:26.826] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:26.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:28:0) after 1m0s
[22:26:26.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:28:0)
[22:26:26.846] Timer tick, asking Buildbucket for the build status
[22:26:27.192] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:27.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:29:0) after 1m0s
[22:27:27.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:29:0)
[22:27:27.228] Timer tick, asking Buildbucket for the build status
[22:27:27.362] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:27.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:30:0) after 1m0s
[22:28:27.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:30:0)
[22:28:27.447] Timer tick, asking Buildbucket for the build status
[22:28:27.815] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:27.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:31:0) after 1m0s
[22:29:27.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:31:0)
[22:29:27.885] Timer tick, asking Buildbucket for the build status
[22:29:28.237] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:28.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:32:0) after 1m0s
[22:30:28.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:32:0)
[22:30:28.367] Timer tick, asking Buildbucket for the build status
[22:30:28.796] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:28.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:33:0) after 1m0s
[22:31:28.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:33:0)
[22:31:28.896] Timer tick, asking Buildbucket for the build status
[22:31:28.978] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:28.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:34:0) after 1m0s
[22:32:29.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:34:0)
[22:32:29.024] Timer tick, asking Buildbucket for the build status
[22:32:29.430] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:29.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:35:0) after 1m0s
[22:33:29.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:35:0)
[22:33:29.496] Timer tick, asking Buildbucket for the build status
[22:33:29.808] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:29.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:36:0) after 1m0s
[22:34:29.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:36:0)
[22:34:29.910] Timer tick, asking Buildbucket for the build status
[22:34:30.002] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:30.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:37:0) after 1m0s
[22:35:30.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:37:0)
[22:35:30.053] Timer tick, asking Buildbucket for the build status
[22:35:30.168] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:30.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:38:0) after 1m0s
[22:36:30.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:38:0)
[22:36:30.242] Timer tick, asking Buildbucket for the build status
[22:36:30.333] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:30.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:39:0) after 1m0s
[22:37:30.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:39:0)
[22:37:30.615] Timer tick, asking Buildbucket for the build status
[22:37:30.690] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:30.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:40:0) after 1m0s
[22:38:30.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:40:0)
[22:38:30.791] Timer tick, asking Buildbucket for the build status
[22:38:30.871] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:30.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:41:0) after 1m0s
[22:39:31.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:41:0)
[22:39:31.078] Timer tick, asking Buildbucket for the build status
[22:39:31.215] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:31.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:42:0) after 1m0s
[22:40:31.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:42:0)
[22:40:31.303] Timer tick, asking Buildbucket for the build status
[22:40:31.389] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:31.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:43:0) after 1m0s
[22:41:31.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:43:0)
[22:41:31.409] Timer tick, asking Buildbucket for the build status
[22:41:31.496] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:31.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:44:0) after 1m0s
[22:42:31.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:44:0)
[22:42:31.589] Timer tick, asking Buildbucket for the build status
[22:42:31.924] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:31.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:45:0) after 1m0s
[22:43:31.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:45:0)
[22:43:31.969] Timer tick, asking Buildbucket for the build status
[22:43:32.387] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:32.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:46:0) after 1m0s
[22:44:32.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:46:0)
[22:44:32.884] Timer tick, asking Buildbucket for the build status
[22:44:33.039] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:33.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:47:0) after 1m0s
[22:45:33.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:47:0)
[22:45:33.172] Timer tick, asking Buildbucket for the build status
[22:45:33.276] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:33.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:48:0) after 1m0s
[22:46:33.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:48:0)
[22:46:33.672] Timer tick, asking Buildbucket for the build status
[22:46:33.751] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:33.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:49:0) after 1m0s
[22:47:33.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:49:0)
[22:47:33.935] Timer tick, asking Buildbucket for the build status
[22:47:34.016] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:34.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:50:0) after 1m0s
[22:48:34.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:50:0)
[22:48:34.133] Timer tick, asking Buildbucket for the build status
[22:48:34.216] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:34.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:51:0) after 1m0s
[22:49:34.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:51:0)
[22:49:34.318] Timer tick, asking Buildbucket for the build status
[22:49:34.637] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:34.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:52:0) after 1m0s
[22:50:34.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:52:0)
[22:50:34.771] Timer tick, asking Buildbucket for the build status
[22:50:34.985] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:34.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:53:0) after 1m0s
[22:51:35.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:53:0)
[22:51:35.001] Timer tick, asking Buildbucket for the build status
[22:51:35.142] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:35.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:54:0) after 1m0s
[22:52:35.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:54:0)
[22:52:35.225] Timer tick, asking Buildbucket for the build status
[22:52:35.349] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:35.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:55:0) after 1m0s
[22:53:35.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:55:0)
[22:53:35.366] Timer tick, asking Buildbucket for the build status
[22:53:35.460] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:35.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:56:0) after 1m0s
[22:54:35.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:56:0)
[22:54:35.550] Timer tick, asking Buildbucket for the build status
[22:54:35.729] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:35.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:57:0) after 1m0s
[22:55:35.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:57:0)
[22:55:35.780] Timer tick, asking Buildbucket for the build status
[22:55:36.029] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:36.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:58:0) after 1m0s
[22:56:36.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:58:0)
[22:56:36.002] Timer tick, asking Buildbucket for the build status
[22:56:36.113] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:36.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:59:0) after 1m0s
[22:57:36.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:59:0)
[22:57:36.140] Timer tick, asking Buildbucket for the build status
[22:57:36.273] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:36.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:60:0) after 1m0s
[22:58:36.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:60:0)
[22:58:36.294] Timer tick, asking Buildbucket for the build status
[22:58:36.385] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:36.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:61:0) after 1m0s
[22:59:36.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:61:0)
[22:59:36.410] Timer tick, asking Buildbucket for the build status
[22:59:36.704] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:36.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:62:0) after 1m0s
[23:00:36.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:62:0)
[23:00:36.757] Timer tick, asking Buildbucket for the build status
[23:00:37.136] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:37.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:63:0) after 1m0s
[23:01:37.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:63:0)
[23:01:37.151] Timer tick, asking Buildbucket for the build status
[23:01:37.647] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:37.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:64:0) after 1m0s
[23:02:37.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:64:0)
[23:02:37.726] Timer tick, asking Buildbucket for the build status
[23:02:37.810] Build 8854740508897182592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:37.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020170571075641376:65:0) after 1m0s
[23:02:48.839] Received PubSub notification, asking Buildbucket for the build status
[23:02:48.923] Build 8854740508897182592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:02:48.923] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613862168096045",
"created_by": "project:chromium",
"created_ts": "1613858420430730",
"id": "8854740508897182592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f93836f1098104c481510db19a1b5dae91659ef8\"}}",
"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\": \"f93836f1098104c481510db19a1b5dae91659ef8\", \"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-us-central1-b-32-1-r8z7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17449, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"d3d30921fc4c74ff24d27c1ad038445fa497046e\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"f93836f1098104c481510db19a1b5dae91659ef8\", \"got_revision_cp\": \"refs/heads/master@{#856117}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"f93836f1098104c481510db19a1b5dae91659ef8\", \"swarm_hashes_refs/heads/master(at){#856117}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"224cd5d4867178e09b104825677121f05eef1267\", \"angle_unittests\": \"6eb4889959e06307e94f3275d21a80d7855d292e\", \"app_shell_unittests\": \"e5c1186643b5ebb1ed50879df0ef93be631be901\", \"aura_unittests\": \"b23cb4b66fd8e7a3122c7e56a6395403469aa797\", \"base_unittests\": \"1ceb22df0f743b895465b4bef1cb2600b451aa3c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"07d1c248318683ee557778896f529805c3b074d2\", \"blink_fuzzer_unittests\": \"ed97cf793d02494f429ea03dfd2e85942d34560f\", \"blink_heap_unittests\": \"83ba697cc9c69b7cce9d1f5474586eef9994039b\", \"blink_platform_unittests\": \"552af90b6473463138708c52caa63788a7756112\", \"blink_unittests\": \"4df9f790562169012988b0e1e30f4fee1e2a581a\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"2b179ddf74b1a8c0715156843d453b26c467141a\", \"capture_unittests\": \"982537f98dffcf3eded682fff17b613ad4465494\", \"cast_unittests\": \"0bff49682381c71a3f2795d33803970ff564ad75\", \"cc_unittests\": \"ccfc73b5039c76d3ced87d89508b10c679fcebb8\", \"chrome_app_unittests\": \"25caab43f16344e4637703713dcbb14257ea7e8f\", \"chrome_cleaner_unittests\": \"8a9f2f7b2224f3a633a57b7e8378596cdfecc0bd\", \"chrome_elf_unittests\": \"3762a422d21692bf0f4116ffa56e4811edbc32c7\", \"chromedriver_unittests\": \"41cf39bf55169f4980303297cf445df94033fc71\", \"components_browsertests\": \"a88e25701c2512f06714fd6df89307dd3bc15bed\", \"components_unittests\": \"95d97f077bd754bd9d26e292f0a414e7e1845e9f\", \"compositor_unittests\": \"bb82eeacdbb9391e714ca3b419d674f128d67686\", \"content_browsertests\": \"f6d3bdc19110222b01dc4cdf512c4eae9cb84f93\", \"content_unittests\": \"d40e705ba57f651de0a07e7e1c2a98401cfe3fa1\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"f026b81c09e147eafbf2bf4754788e0fb30f46d7\", \"cronet_unittests\": \"87a32b0e6371d65958025e887cfc647480c8fb78\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"766b4fc65a908eab13826ad0637497c1450903b2\", \"device_unittests\": \"9bf2b188182da54c895a762c3e463b0656bff048\", \"display_unittests\": \"b12e9f65f970d5b728d54d426635d444bae72692\", \"elevation_service_unittests\": \"cded0d9af18835f82f3e9115eae3df94fe71d0a6\", \"events_unittests\": \"31f6330f4a7ecf99b2146fbb2272a02dbf8affc0\", \"extensions_browsertests\": \"9f57f1772e83fc6f77b754342abe63d8a05e8b0c\", \"extensions_unittests\": \"49e809eed76dfe388ee53d3a18f0537584bb8007\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"61f378f481fabc8392209e8d6e4a8abbbd7905d0\", \"gcp_unittests\": \"1253d26b12b7046e148aadcaf8a4264f98533f55\", \"gfx_unittests\": \"5592cc1deaaa6c171c909372a7a851524fcb3266\", \"gin_unittests\": \"4870a5d97c4ee8fa44241007482db1c691266529\", \"google_apis_unittests\": \"b27648f0244a23ad73845d21ad68e6d8079298f9\", \"gpu_unittests\": \"eb4e1b58a8187eb6bfc81f54d6780bb635d3c50c\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"b8646bae48fbbead97c07c35102769660ef8348f\", \"headless_unittests\": \"de9bdd6fced54b149b239c7914ebbb9cbc17a879\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"ee3f5ff0a4a42682dfbae3d8b2e86a9635702b31\", \"interactive_ui_tests\": \"5dce03bbc85873e34f5979deeaaf8885545323f8\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"5e0efaab74b5a2dee3a9de9d2ef27554ba50e881\", \"latency_unittests\": \"ea2685b6f4604621d06d795d51c6b20e250a0671\", \"libjingle_xmpp_unittests\": \"bf0b14490060fd4f08c16b4e5b8ab594c84e0ecd\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"83459a5cb9b147d134dee0a10aa9965d37620071\", \"media_unittests\": \"7b685ed1e5b10274aa4aaac040dd4c0f0a189352\", \"message_center_unittests\": \"c437ee3d3ed3b50bc66634cc73546cc639f99011\", \"midi_unittests\": \"98373d8b72aae903046e691418cfa863230caa2d\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"d10b02db6a9fd3f3af951072a87b8626605e4a01\", \"nacl_loader_unittests\": \"217e3f5cbe86e2b9c499615b4ab2cb50069cc30b\", \"native_theme_unittests\": \"af7fdd6abafbb4e4040b361e96d9fda89ec3fc7f\", \"net_unittests\": \"429084f3544828d66d866249b464b62349f1fa37\", \"notification_helper_unittests\": \"78412712a560368e0a13bde4b8e03cff056161e4\", \"pdf_unittests\": \"8f068286ad22ff6a161d34c28ec96ea4d37792a7\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"b0d2ca6661bf2b5fed7c5f06ee69219464494c48\", \"printing_unittests\": \"956d8f5cfb5e61a0363ef2f71e7c416dbd6318dc\", \"remoting_unittests\": \"aa880b0a9048fdb7012291bfbe7829cb78ab5921\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"8c64820cc57c54cb99fdca744583646bd1bb8ce7\", \"services_unittests\": \"963587630e1a7aa1067dd6772fcec82145b06035\", \"setup_unittests\": \"8ffe48bee602be3473209f2820ce06a1599eb0a7\", \"shell_dialogs_unittests\": \"6686c812ebdd151dce490c803b6115f783b34dd0\", \"skia_unittests\": \"090e384862540cc73c663153aee0b469cc37b322\", \"snapshot_unittests\": \"a177fc3c97cb20a389343f5d4772253dd93db4b3\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"7d9637b8c692ac1797affec2118395051f14e41e\", \"sync_integration_tests\": \"4f36b90319d56eab97f78b4c647994990e483d82\", \"traffic_annotation_auditor_unittests\": \"774059a4d1525c99a60dd3eee241c4046b561136\", \"ui_base_unittests\": \"bf164caf68463b60f5ec40894f74fcc976c6884d\", \"ui_touch_selection_unittests\": \"2a50b6275f7aa587fd45dd97a14e426bff2e7456\", \"unit_tests\": \"b2e9f08aba5352b2c34ffac377eb34667fda8fc5\", \"updater_tests\": \"d9d221e44647d16cf2e491093bdcd9adce46e7b6\", \"url_unittests\": \"9e21669aae25157e821cf8e8da3282e69e2d943c\", \"views_unittests\": \"c00e83effb478de94879773d9b1379f1a63881be\", \"viz_unittests\": \"4913b2cc08e1de77b0c1cd0bd1c83af8009754da\", \"vr_common_unittests\": \"712f51bd90537b34e9175dc8d645a0a1fcc3462b\", \"vr_pixeltests\": \"1c122d4ced395fecd543dafd242176d6230f7a81\", \"weblayer_browsertests\": \"ac67e7b8fd17540185aa6f19bbb8a8d5acad64af\", \"weblayer_unittests\": \"d3896bfd586ad4664d28d6708d2c205a4988cab5\", \"wm_unittests\": \"14def547728b0d2081ed87b234fbcc71475952a7\", \"wtf_unittests\": \"83a9e50dbac9bb26ab85fb695ba8f17f333a742a\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-r8z7\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613858423210917",
"status": "COMPLETED",
"status_changed_ts": "1613862168390327",
"tags": [
"build_address:luci.chromium.ci/win-asan/17449",
"builder:win-asan",
"buildset:commit/git/f93836f1098104c481510db19a1b5dae91659ef8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f93836f1098104c481510db19a1b5dae91659ef8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020170571075641376",
"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/8854740508897182592/+/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:51da46c3a3231510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613862168394603",
"url": "https://ci.chromium.org/b/8854740508897182592",
"utcnow_ts": "1613862168915636"
}
}
[23:02:48.923] Invocation finished in 1h2m29.954375103s with status SUCCEEDED