[17:34:15.109] New invocation is queued and will start shortly
[17:34:16.220] Starting the invocation (attempt 1)
[17:34:16.258] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:34:16.258] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:34:16.258] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019734325579429072",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019734325579429072",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master"
]
}
[17:34:17.455] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614274456376551",
"id": "8854304263589811392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614274457188883",
"tags": [
"build_address:luci.chromium.ci/win-asan/17530",
"builder:win-asan",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019734325579429072",
"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/8854304263589811392/+/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": "1614274457189057",
"url": "https://ci.chromium.org/b/8854304263589811392",
"utcnow_ts": "1614274457439465"
}
}
[17:34:17.456] Task URL: https://ci.chromium.org/b/8854304263589811392
[17:34:17.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:2:0) after 1m0s
[17:34:21.706] Received PubSub notification, asking Buildbucket for the build status
[17:34:21.827] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:17.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:2:0)
[17:35:17.640] Timer tick, asking Buildbucket for the build status
[17:35:17.749] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:17.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:4:0) after 1m0s
[17:36:17.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:4:0)
[17:36:17.877] Timer tick, asking Buildbucket for the build status
[17:36:17.976] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:17.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:5:0) after 1m0s
[17:37:18.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:5:0)
[17:37:18.027] Timer tick, asking Buildbucket for the build status
[17:37:18.142] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:18.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:6:0) after 1m0s
[17:38:18.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:6:0)
[17:38:18.217] Timer tick, asking Buildbucket for the build status
[17:38:18.507] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:18.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:7:0) after 1m0s
[17:39:18.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:7:0)
[17:39:18.591] Timer tick, asking Buildbucket for the build status
[17:39:19.106] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:19.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:8:0) after 1m0s
[17:40:19.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:8:0)
[17:40:19.117] Timer tick, asking Buildbucket for the build status
[17:40:19.217] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:19.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:9:0) after 1m0s
[17:41:19.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:9:0)
[17:41:19.237] Timer tick, asking Buildbucket for the build status
[17:41:19.341] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:19.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:10:0) after 1m0s
[17:42:19.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:10:0)
[17:42:19.358] Timer tick, asking Buildbucket for the build status
[17:42:19.457] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:19.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:11:0) after 1m0s
[17:43:20.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:11:0)
[17:43:20.046] Timer tick, asking Buildbucket for the build status
[17:43:20.132] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:20.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:12:0) after 1m0s
[17:44:20.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:12:0)
[17:44:20.183] Timer tick, asking Buildbucket for the build status
[17:44:20.276] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:20.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:13:0) after 1m0s
[17:45:20.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:13:0)
[17:45:20.322] Timer tick, asking Buildbucket for the build status
[17:45:20.601] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:20.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:14:0) after 1m0s
[17:46:20.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:14:0)
[17:46:20.602] Timer tick, asking Buildbucket for the build status
[17:46:20.716] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:20.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:15:0) after 1m0s
[17:47:20.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:15:0)
[17:47:20.728] Timer tick, asking Buildbucket for the build status
[17:47:20.806] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:20.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:16:0) after 1m0s
[17:48:20.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:16:0)
[17:48:20.855] Timer tick, asking Buildbucket for the build status
[17:48:20.931] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:20.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:17:0) after 1m0s
[17:49:20.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:17:0)
[17:49:20.927] Timer tick, asking Buildbucket for the build status
[17:49:20.999] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:21.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:18:0) after 1m0s
[17:50:21.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:18:0)
[17:50:21.141] Timer tick, asking Buildbucket for the build status
[17:50:21.227] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:21.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:19:0) after 1m0s
[17:51:21.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:19:0)
[17:51:21.313] Timer tick, asking Buildbucket for the build status
[17:51:21.671] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:21.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:20:0) after 1m0s
[17:52:21.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:20:0)
[17:52:21.717] Timer tick, asking Buildbucket for the build status
[17:52:21.791] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:21.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:21:0) after 1m0s
[17:53:21.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:21:0)
[17:53:21.765] Timer tick, asking Buildbucket for the build status
[17:53:21.968] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:22:0) after 1m0s
[17:54:21.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:22:0)
[17:54:21.992] Timer tick, asking Buildbucket for the build status
[17:54:22.311] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:22.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:23:0) after 1m0s
[17:55:22.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:23:0)
[17:55:22.543] Timer tick, asking Buildbucket for the build status
[17:55:23.078] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:23.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:24:0) after 1m0s
[17:56:23.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:24:0)
[17:56:23.136] Timer tick, asking Buildbucket for the build status
[17:56:23.227] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:23.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:25:0) after 1m0s
[17:57:23.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:25:0)
[17:57:23.318] Timer tick, asking Buildbucket for the build status
[17:57:23.711] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:23.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:26:0) after 1m0s
[17:58:23.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:26:0)
[17:58:23.773] Timer tick, asking Buildbucket for the build status
[17:58:23.859] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:23.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:27:0) after 1m0s
[17:59:23.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:27:0)
[17:59:23.903] Timer tick, asking Buildbucket for the build status
[17:59:24.001] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:24.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:28:0) after 1m0s
[18:00:24.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:28:0)
[18:00:24.011] Timer tick, asking Buildbucket for the build status
[18:00:24.270] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:29:0) after 1m0s
[18:01:24.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:29:0)
[18:01:24.321] Timer tick, asking Buildbucket for the build status
[18:01:24.972] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:24.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:30:0) after 1m0s
[18:02:25.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:30:0)
[18:02:25.000] Timer tick, asking Buildbucket for the build status
[18:02:25.387] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:25.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:31:0) after 1m0s
[18:03:25.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:31:0)
[18:03:25.404] Timer tick, asking Buildbucket for the build status
[18:03:25.713] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:25.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:32:0) after 1m0s
[18:04:25.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:32:0)
[18:04:25.784] Timer tick, asking Buildbucket for the build status
[18:04:25.872] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:25.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:33:0) after 1m0s
[18:05:25.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:33:0)
[18:05:25.856] Timer tick, asking Buildbucket for the build status
[18:05:25.951] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:25.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:34:0) after 1m0s
[18:06:25.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:34:0)
[18:06:25.964] Timer tick, asking Buildbucket for the build status
[18:06:26.069] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:26.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:35:0) after 1m0s
[18:07:26.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:35:0)
[18:07:26.157] Timer tick, asking Buildbucket for the build status
[18:07:26.239] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:26.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:36:0) after 1m0s
[18:08:26.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:36:0)
[18:08:26.260] Timer tick, asking Buildbucket for the build status
[18:08:26.358] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:26.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:37:0) after 1m0s
[18:09:26.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:37:0)
[18:09:26.358] Timer tick, asking Buildbucket for the build status
[18:09:26.532] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:26.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:38:0) after 1m0s
[18:10:26.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:38:0)
[18:10:26.968] Timer tick, asking Buildbucket for the build status
[18:10:27.059] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:27.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:39:0) after 1m0s
[18:11:27.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:39:0)
[18:11:27.269] Timer tick, asking Buildbucket for the build status
[18:11:27.491] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:27.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:40:0) after 1m0s
[18:12:27.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:40:0)
[18:12:27.586] Timer tick, asking Buildbucket for the build status
[18:12:27.699] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:27.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:41:0) after 1m0s
[18:13:28.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:41:0)
[18:13:28.241] Timer tick, asking Buildbucket for the build status
[18:13:28.352] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:28.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:42:0) after 1m0s
[18:14:28.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:42:0)
[18:14:28.562] Timer tick, asking Buildbucket for the build status
[18:14:28.932] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:28.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:43:0) after 1m0s
[18:15:28.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:43:0)
[18:15:28.977] Timer tick, asking Buildbucket for the build status
[18:15:29.167] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:29.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:44:0) after 1m0s
[18:16:31.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:44:0)
[18:16:31.025] Timer tick, asking Buildbucket for the build status
[18:16:31.147] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:31.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:45:0) after 1m0s
[18:17:31.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:45:0)
[18:17:31.206] Timer tick, asking Buildbucket for the build status
[18:17:31.589] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:31.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:46:0) after 1m0s
[18:18:31.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:46:0)
[18:18:31.696] Timer tick, asking Buildbucket for the build status
[18:18:31.805] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:31.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:47:0) after 1m0s
[18:19:32.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:47:0)
[18:19:32.019] Timer tick, asking Buildbucket for the build status
[18:19:32.209] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:32.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:48:0) after 1m0s
[18:20:32.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:48:0)
[18:20:32.310] Timer tick, asking Buildbucket for the build status
[18:20:32.658] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:32.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:49:0) after 1m0s
[18:21:32.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:49:0)
[18:21:32.845] Timer tick, asking Buildbucket for the build status
[18:21:33.365] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:33.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:50:0) after 1m0s
[18:22:33.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:50:0)
[18:22:33.426] Timer tick, asking Buildbucket for the build status
[18:22:33.550] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:33.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:51:0) after 1m0s
[18:23:33.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:51:0)
[18:23:33.591] Timer tick, asking Buildbucket for the build status
[18:23:33.666] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:33.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:52:0) after 1m0s
[18:24:33.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:52:0)
[18:24:33.795] Timer tick, asking Buildbucket for the build status
[18:24:33.946] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:33.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:53:0) after 1m0s
[18:25:34.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:53:0)
[18:25:34.021] Timer tick, asking Buildbucket for the build status
[18:25:34.106] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:34.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:54:0) after 1m0s
[18:26:34.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:54:0)
[18:26:34.151] Timer tick, asking Buildbucket for the build status
[18:26:34.257] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:34.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:55:0) after 1m0s
[18:27:34.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:55:0)
[18:27:34.271] Timer tick, asking Buildbucket for the build status
[18:27:34.354] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:34.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:56:0) after 1m0s
[18:28:34.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:56:0)
[18:28:34.454] Timer tick, asking Buildbucket for the build status
[18:28:34.579] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:34.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:57:0) after 1m0s
[18:29:34.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:57:0)
[18:29:34.606] Timer tick, asking Buildbucket for the build status
[18:29:34.909] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:34.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:58:0) after 1m0s
[18:30:34.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:58:0)
[18:30:34.930] Timer tick, asking Buildbucket for the build status
[18:30:35.015] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:35.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:59:0) after 1m0s
[18:31:35.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:59:0)
[18:31:35.130] Timer tick, asking Buildbucket for the build status
[18:31:35.232] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:35.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:60:0) after 1m0s
[18:32:35.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:60:0)
[18:32:35.353] Timer tick, asking Buildbucket for the build status
[18:32:35.815] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:35.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:61:0) after 1m0s
[18:33:35.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:61:0)
[18:33:35.870] Timer tick, asking Buildbucket for the build status
[18:33:35.969] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:35.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:62:0) after 1m0s
[18:34:36.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:62:0)
[18:34:36.026] Timer tick, asking Buildbucket for the build status
[18:34:36.141] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:36.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:63:0) after 1m0s
[18:35:36.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:63:0)
[18:35:36.123] Timer tick, asking Buildbucket for the build status
[18:35:36.415] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:36.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:64:0) after 1m0s
[18:36:36.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:64:0)
[18:36:36.505] Timer tick, asking Buildbucket for the build status
[18:36:36.664] Build 8854304263589811392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:36.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019734325579429072:65:0) after 1m0s
[18:36:41.370] Received PubSub notification, asking Buildbucket for the build status
[18:36:41.953] Build 8854304263589811392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:36:41.953] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614278200044040",
"created_by": "project:chromium",
"created_ts": "1614274456376551",
"id": "8854304263589811392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"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\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"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-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17530, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"got_revision_cp\": \"refs/heads/master@{#857676}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd5127b11e04b7f49031b2412625c36e4a86c3da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33340}\", \"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\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"swarm_hashes_refs/heads/master(at){#857676}_without_patch\": {\"absl_hardening_tests\": \"684a3f08e1a36cecd08d97097e2dacfb83becee4\", \"accessibility_unittests\": \"8f6fee0edcb238173b8d212d5ab538a9da84884d\", \"angle_unittests\": \"9f7b2fa7554af531d0d5b27d4fc8c550103f6723\", \"app_shell_unittests\": \"578b3aec8455bd1ac1f707eb30a433e52e8f63a5\", \"aura_unittests\": \"9a8a7177cb60ea6cea1d16d8083bb8c0a00fad13\", \"base_unittests\": \"f59968c5579ab60741bb6c42c1649f689e7c33b9\", \"base_util_unittests\": \"d0c493cd905025a2fc56166bc0f32c13416286a9\", \"blink_common_unittests\": \"c6a450cef433294401d654730bce4f3d8682a098\", \"blink_fuzzer_unittests\": \"7a9cf4ab439c32543b887a96e045c7ac00a3867d\", \"blink_heap_unittests\": \"46653a1eeb2c2c3812c35e4e10c19209380c564b\", \"blink_platform_unittests\": \"c996c14d4d48249a9a5298ab5ac55505cb662fce\", \"blink_unittests\": \"d830d65957a160a79948a10c6eb1e35ebaecd3b4\", \"boringssl_crypto_tests\": \"008e4b792f2876d5be6f2c6d04915150e553939b\", \"boringssl_ssl_tests\": \"16cec33a1f19bd538a6d1255724d06ecfa3bc04a\", \"browser_switcher_bho_unittests\": \"d9c609656f5f56c340e23ffcbcb0c2418c574d24\", \"browser_tests\": \"06ea13628f655fbb0719aac4ae5b346fea397c6a\", \"capture_unittests\": \"bc9e2356b715cf682b58fd07dc7d0f8fbbb150a9\", \"cast_unittests\": \"7709e401226e3128ddfaf7dfe4fafd2ea7d64329\", \"cc_unittests\": \"2166d3e2146fd3b1967a82cb14d938867df4347c\", \"chrome_app_unittests\": \"5e44bb107d41229665f7b32a3c3232d319b6da81\", \"chrome_cleaner_unittests\": \"4f37d491ed7721c1e69443f719396cd7a84c5f66\", \"chrome_elf_unittests\": \"b335fd8d8e341194b793f16642264cabc856e045\", \"chromedriver_unittests\": \"347c5fdeea18f14e243940602ad9c268be8835d6\", \"color_unittests\": \"be4501a9dec88e7c1cfaeb10e533210bb181a00b\", \"components_browsertests\": \"3540516dfab9c25c2f76dcd2d50dcd3c917d253a\", \"components_unittests\": \"f1641f63003630d0613350b18c0e701eace0c3a5\", \"compositor_unittests\": \"09a20497a20261d0aae0dd0d5e665342337cc094\", \"content_browsertests\": \"868ef0cf14ffd249ca83553eecc02050022cee57\", \"content_unittests\": \"6f68f905db8699dd5532e874d12e8583501d37bd\", \"courgette_unittests\": \"54eb956589bf0c068f12ccb8363a4355cb1b269c\", \"crashpad_tests\": \"55c9cc66a5c2afff97976c65b611badf8b23951c\", \"cronet_tests\": \"072ed6c71150c730330380fefd1b5d4b336faf8e\", \"cronet_unittests\": \"f35d7c785d28a2593b4d723ead6e659638ccb6e0\", \"crypto_unittests\": \"5737d145a534d8bfb4795f04cbeda9c5b7789608\", \"delayloads_unittests\": \"b6aa31ce2c911d702912c7bae5e04b01d77b6cf9\", \"device_unittests\": \"e0173db18ca43fdda65152244d44c32b30ae6bc5\", \"display_unittests\": \"03005308e85754d5465d53b3cd2843bc7342a696\", \"elevation_service_unittests\": \"d1c0de7b958e191f75cba3f3dd9d50efabd6bf19\", \"events_unittests\": \"782492f9ff70bf615c80be06f3c4c72cb386bb91\", \"extensions_browsertests\": \"881501e35e7c18477fa60c9dbc5f4bc00b1b96f8\", \"extensions_unittests\": \"9bb8736cbcafd2cebe30070875056b68bc8f28c2\", \"filesystem_service_unittests\": \"c4e5acf740928a185f743d45e709393015f4b80d\", \"gcm_unit_tests\": \"bf7b28e056606cdf5927b600295e657f99b5ec81\", \"gcp_unittests\": \"29c646d8b7643a1fb21dae725ba79fc241611398\", \"gfx_unittests\": \"0ee1a418fbe25e9aaea82bc3236757564bf016f6\", \"gin_unittests\": \"d6d54f578cf5bbbbcdf0fb3b517a8c7de014aa40\", \"google_apis_unittests\": \"34a910e2b7fa974573c059e190a06eade6dd8c10\", \"gpu_unittests\": \"92da97a8209c8b487ea68f4393e6bd3609eff9ec\", \"gwp_asan_unittests\": \"b4da9c4ad0f470f1931b13b2f376d7b0e30416b3\", \"headless_browsertests\": \"b6a0e4e2bffb4145767a4c3499f850d1a34949cb\", \"headless_unittests\": \"58743ea6b06de226dc4365bc5f6ef3d53df7f562\", \"install_static_unittests\": \"6a6f4da78a6e70cf0d5c7e28724e284adfd1063d\", \"installer_util_unittests\": \"cef39fe9fdf7275e7edd2e5687c31b897e310ce9\", \"interactive_ui_tests\": \"e6835d05e9bd9f2a1c0f2b0c774d365924c60360\", \"ipc_tests\": \"8a7798c74c9d92484addb5b736f334882f8f660e\", \"jingle_unittests\": \"81d36fb37a1f4553d7b6aa3a0f917dfade958c9f\", \"latency_unittests\": \"c238eb499d2ffb2a6cb36dce199c861c43bf77e7\", \"libjingle_xmpp_unittests\": \"b5912b03b640912fb008b6785908a4038c6dae08\", \"liburlpattern_unittests\": \"b78b4757049d7e35dbdb4aafd6f246e1ce9ac4f1\", \"media_blink_unittests\": \"4b99bf106fd7eb1598d33e186718f59b16c06b0d\", \"media_unittests\": \"4b7928e6fb183603f9e3c8106a184a8e5efea4b9\", \"message_center_unittests\": \"9ddc1e31d9843d2d19b4003fe5e99bfc0dddf82e\", \"midi_unittests\": \"850656c0bd7969ca42b099204599df7f6e583c67\", \"mojo_core_unittests\": \"a94fa9a9782fe48d29e1ffdb8e7494c48fb35e08\", \"mojo_unittests\": \"8016f1dbe4688d1fc5fd98dee9059dab97994ce7\", \"nacl_loader_unittests\": \"8c0b47832ef66088a4971b08a3201d8793cbedcc\", \"native_theme_unittests\": \"a62ec11289ae2a0e6fcbb54f8fc0f282d46c80e4\", \"net_unittests\": \"71126748f1b3603e5dadec848b3f052308f29497\", \"notification_helper_unittests\": \"6b9f2b59ca3b66d6a061d3faae67b04e49243c63\", \"pdf_unittests\": \"c0a97e63ee2218c162e320cbca7f371cf80c1555\", \"perfetto_unittests\": \"2b7069f812585084152e12221820fec3e79dfb50\", \"ppapi_unittests\": \"1ee92b8ecfe7bcb011ad643b345f9346aa60048c\", \"printing_unittests\": \"9156989b986e01b5fdba01fe57a732c6a2fe6bab\", \"remoting_unittests\": \"7157f3a6a532d68c6b6abd1b04fb31f8f9c9147d\", \"sbox_integration_tests\": \"9f50f5dd3bb98fafc65a6772f2a7203a668bcf01\", \"sbox_unittests\": \"36c5c6f037d43a908ab686a9202c6a4e0b3d54e7\", \"sbox_validation_tests\": \"c94fe5456aec715203a5f105ad82880a325df1e3\", \"service_manager_unittests\": \"0444e9b882e64e4af05ab3892b11cd54cef6b83b\", \"services_unittests\": \"1cfa2c6876b448629735aebae45f931d50630f13\", \"setup_unittests\": \"754898d1e7f6f347272d7e5e4f39f1be7b911bc5\", \"shell_dialogs_unittests\": \"4fe3a0b54bb2d4812985e21f26ce6cae93429fb3\", \"skia_unittests\": \"2dcd0708b61d7c4b5844542fc4479b4fa40c9e24\", \"snapshot_unittests\": \"30a4fe635acbb07eaa143e56dafc8cb839fa1973\", \"sql_unittests\": \"cc3986588614ead17a3558846c80494c84879774\", \"storage_unittests\": \"24058afd17601a182096f689281a038ac0f9bbb9\", \"sync_integration_tests\": \"a335e32cb7537a195e931d2fd408ba34275f61ef\", \"traffic_annotation_auditor_unittests\": \"7fe9d574fda8b599c686977186a4ae2b483a972d\", \"ui_base_unittests\": \"2e19c580b11325d9f5064fc0e3e61b0cf429a4d2\", \"ui_touch_selection_unittests\": \"d15343f86f457ee572ca4e779ee31f485ea27388\", \"unit_tests\": \"bc0528bbd5d65ff58ea956284d0c14c4b44d1b0f\", \"updater_tests\": \"ef8c9cca4dc64c9b87b199d1fcbd457db0d94831\", \"url_unittests\": \"5d26c3b3bc6fb57a49543c4537a113304318a8c8\", \"views_unittests\": \"71de5148dbcadd00467a8797ce69568473358b11\", \"viz_unittests\": \"3745dd6f9332846b0f6ad1c0409341e0973b2fe4\", \"vr_common_unittests\": \"a5a9b7ee0148fdaf474da5b8f51298b7cb4f175a\", \"vr_pixeltests\": \"f50a376e2946677005e8f375e00ce376aa97875a\", \"weblayer_browsertests\": \"b4dc460fd2059248ebd317a1c3b41742a3a6a74f\", \"weblayer_unittests\": \"e23ab01ffdb39843416359d905d5af1f3f9ac972\", \"wm_unittests\": \"5591ddf28bb4b93fdecec547b1abe72084915bba\", \"wtf_unittests\": \"e430fe383f0325c9c4ce727d0402ee5c5ca2f266\", \"zlib_unittests\": \"f277bc8534a3901876d1525e81c17a7613a4b3c8\", \"zucchini_unittests\": \"8f069d11c277b2fa737e7f15a72c67f9aaeeedcb\"}}, \"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-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614274460899289",
"status": "COMPLETED",
"status_changed_ts": "1614278200585214",
"tags": [
"build_address:luci.chromium.ci/win-asan/17530",
"builder:win-asan",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019734325579429072",
"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/8854304263589811392/+/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:51f312f845509f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614278200657937",
"url": "https://ci.chromium.org/b/8854304263589811392",
"utcnow_ts": "1614278201929157"
}
}
[18:36:41.954] Invocation finished in 1h2m26.859097885s with status SUCCEEDED