[14:17:46.651] New invocation is queued and will start shortly
[14:17:47.805] Starting the invocation (attempt 1)
[14:17:47.852] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:17:47.852] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:17:47.852] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023461162297168208",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f0d6de792a1d6c39624481bcfd60294c932726f5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023461162297168208",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f0d6de792a1d6c39624481bcfd60294c932726f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0d6de792a1d6c39624481bcfd60294c932726f5",
"gitiles_ref:refs/heads/master"
]
}
[14:17:48.601] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610720267950883",
"id": "8858031100272094144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0d6de792a1d6c39624481bcfd60294c932726f5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610720268390539",
"tags": [
"build_address:luci.chromium.ci/win-asan/16839",
"builder:win-asan",
"buildset:commit/git/f0d6de792a1d6c39624481bcfd60294c932726f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0d6de792a1d6c39624481bcfd60294c932726f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023461162297168208",
"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/8858031100272094144/+/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": "1610720268390646",
"url": "https://ci.chromium.org/b/8858031100272094144",
"utcnow_ts": "1610720268592417"
}
}
[14:17:48.601] Task URL: https://ci.chromium.org/b/8858031100272094144
[14:17:48.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:2:0) after 1m0s
[14:17:51.359] Received PubSub notification, asking Buildbucket for the build status
[14:17:51.454] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:2:0)
[14:18:48.626] Timer tick, asking Buildbucket for the build status
[14:18:48.959] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:4:0) after 1m0s
[14:19:49.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:4:0)
[14:19:49.340] Timer tick, asking Buildbucket for the build status
[14:19:49.635] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:49.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:5:0) after 1m0s
[14:20:49.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:5:0)
[14:20:49.796] Timer tick, asking Buildbucket for the build status
[14:20:50.092] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:50.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:6:0) after 1m0s
[14:21:50.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:6:0)
[14:21:50.175] Timer tick, asking Buildbucket for the build status
[14:21:50.324] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:50.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:7:0) after 1m0s
[14:22:50.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:7:0)
[14:22:50.455] Timer tick, asking Buildbucket for the build status
[14:22:50.762] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:50.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:8:0) after 1m0s
[14:23:50.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:8:0)
[14:23:50.845] Timer tick, asking Buildbucket for the build status
[14:23:50.905] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:50.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:9:0) after 1m0s
[14:24:50.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:9:0)
[14:24:50.916] Timer tick, asking Buildbucket for the build status
[14:24:51.012] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:51.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:10:0) after 1m0s
[14:25:51.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:10:0)
[14:25:51.065] Timer tick, asking Buildbucket for the build status
[14:25:51.198] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:51.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:11:0) after 1m0s
[14:26:51.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:11:0)
[14:26:51.251] Timer tick, asking Buildbucket for the build status
[14:26:51.496] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:51.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:12:0) after 1m0s
[14:27:51.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:12:0)
[14:27:51.624] Timer tick, asking Buildbucket for the build status
[14:27:51.741] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:51.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:13:0) after 1m0s
[14:28:51.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:13:0)
[14:28:51.834] Timer tick, asking Buildbucket for the build status
[14:28:51.936] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:51.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:14:0) after 1m0s
[14:29:51.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:14:0)
[14:29:51.984] Timer tick, asking Buildbucket for the build status
[14:29:52.059] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:52.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:15:0) after 1m0s
[14:30:52.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:15:0)
[14:30:52.026] Timer tick, asking Buildbucket for the build status
[14:30:52.295] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:52.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:16:0) after 1m0s
[14:31:52.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:16:0)
[14:31:52.326] Timer tick, asking Buildbucket for the build status
[14:31:52.515] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:52.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:17:0) after 1m0s
[14:32:52.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:17:0)
[14:32:52.594] Timer tick, asking Buildbucket for the build status
[14:32:52.826] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:52.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:18:0) after 1m0s
[14:33:52.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:18:0)
[14:33:52.853] Timer tick, asking Buildbucket for the build status
[14:33:52.972] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:52.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:19:0) after 1m0s
[14:34:53.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:19:0)
[14:34:53.053] Timer tick, asking Buildbucket for the build status
[14:34:53.311] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:53.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:20:0) after 1m0s
[14:35:53.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:20:0)
[14:35:53.351] Timer tick, asking Buildbucket for the build status
[14:35:53.469] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:21:0) after 1m0s
[14:36:53.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:21:0)
[14:36:53.520] Timer tick, asking Buildbucket for the build status
[14:36:53.646] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:53.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:22:0) after 1m0s
[14:37:53.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:22:0)
[14:37:53.641] Timer tick, asking Buildbucket for the build status
[14:37:53.808] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:53.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:23:0) after 1m0s
[14:38:53.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:23:0)
[14:38:53.889] Timer tick, asking Buildbucket for the build status
[14:38:54.023] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:54.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:24:0) after 1m0s
[14:39:54.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:24:0)
[14:39:54.119] Timer tick, asking Buildbucket for the build status
[14:39:54.219] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:54.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:25:0) after 1m0s
[14:40:54.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:25:0)
[14:40:54.313] Timer tick, asking Buildbucket for the build status
[14:40:54.503] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:54.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:26:0) after 1m0s
[14:41:54.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:26:0)
[14:41:54.545] Timer tick, asking Buildbucket for the build status
[14:41:54.648] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:54.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:27:0) after 1m0s
[14:42:54.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:27:0)
[14:42:54.663] Timer tick, asking Buildbucket for the build status
[14:42:54.780] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:54.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:28:0) after 1m0s
[14:43:54.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:28:0)
[14:43:54.820] Timer tick, asking Buildbucket for the build status
[14:43:54.924] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:54.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:29:0) after 1m0s
[14:44:54.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:29:0)
[14:44:54.972] Timer tick, asking Buildbucket for the build status
[14:44:55.221] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:30:0) after 1m0s
[14:45:55.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:30:0)
[14:45:55.257] Timer tick, asking Buildbucket for the build status
[14:45:55.342] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:55.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:31:0) after 1m0s
[14:46:55.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:31:0)
[14:46:55.457] Timer tick, asking Buildbucket for the build status
[14:46:55.608] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:55.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:32:0) after 1m0s
[14:47:55.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:32:0)
[14:47:55.675] Timer tick, asking Buildbucket for the build status
[14:47:55.806] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:55.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:33:0) after 1m0s
[14:48:55.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:33:0)
[14:48:55.839] Timer tick, asking Buildbucket for the build status
[14:48:56.236] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:56.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:34:0) after 1m0s
[14:49:56.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:34:0)
[14:49:56.256] Timer tick, asking Buildbucket for the build status
[14:49:56.322] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:56.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:35:0) after 1m0s
[14:50:56.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:35:0)
[14:50:56.374] Timer tick, asking Buildbucket for the build status
[14:50:56.529] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:36:0) after 1m0s
[14:51:56.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:36:0)
[14:51:56.555] Timer tick, asking Buildbucket for the build status
[14:51:56.816] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:56.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:37:0) after 1m0s
[14:52:56.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:37:0)
[14:52:56.843] Timer tick, asking Buildbucket for the build status
[14:52:57.515] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:57.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:38:0) after 1m0s
[14:53:57.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:38:0)
[14:53:57.528] Timer tick, asking Buildbucket for the build status
[14:53:57.598] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:39:0) after 1m0s
[14:54:57.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:39:0)
[14:54:57.720] Timer tick, asking Buildbucket for the build status
[14:54:57.826] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:57.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:40:0) after 1m0s
[14:55:57.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:40:0)
[14:55:57.959] Timer tick, asking Buildbucket for the build status
[14:55:58.416] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:58.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:41:0) after 1m0s
[14:56:58.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:41:0)
[14:56:58.481] Timer tick, asking Buildbucket for the build status
[14:56:58.611] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:58.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:42:0) after 1m0s
[14:57:58.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:42:0)
[14:57:58.885] Timer tick, asking Buildbucket for the build status
[14:57:58.994] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:43:0) after 1m0s
[14:58:59.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:43:0)
[14:58:59.206] Timer tick, asking Buildbucket for the build status
[14:58:59.358] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:59.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:44:0) after 1m0s
[14:59:59.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:44:0)
[14:59:59.483] Timer tick, asking Buildbucket for the build status
[14:59:59.905] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:59.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:45:0) after 1m0s
[15:00:59.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:45:0)
[15:00:59.954] Timer tick, asking Buildbucket for the build status
[15:01:00.184] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:00.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:46:0) after 1m0s
[15:02:00.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:46:0)
[15:02:00.210] Timer tick, asking Buildbucket for the build status
[15:02:00.499] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:00.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:47:0) after 1m0s
[15:03:00.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:47:0)
[15:03:00.566] Timer tick, asking Buildbucket for the build status
[15:03:00.775] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:00.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:48:0) after 1m0s
[15:04:01.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:48:0)
[15:04:01.348] Timer tick, asking Buildbucket for the build status
[15:04:01.731] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:01.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:49:0) after 1m0s
[15:05:01.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:49:0)
[15:05:01.803] Timer tick, asking Buildbucket for the build status
[15:05:01.995] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:01.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:50:0) after 1m0s
[15:06:02.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:50:0)
[15:06:02.034] Timer tick, asking Buildbucket for the build status
[15:06:02.163] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:02.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:51:0) after 1m0s
[15:07:02.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:51:0)
[15:07:02.197] Timer tick, asking Buildbucket for the build status
[15:07:02.345] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:02.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:52:0) after 1m0s
[15:08:02.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:52:0)
[15:08:02.391] Timer tick, asking Buildbucket for the build status
[15:08:02.516] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:02.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:53:0) after 1m0s
[15:09:02.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:53:0)
[15:09:02.689] Timer tick, asking Buildbucket for the build status
[15:09:02.758] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:02.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:54:0) after 1m0s
[15:10:02.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:54:0)
[15:10:02.832] Timer tick, asking Buildbucket for the build status
[15:10:02.926] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:02.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:55:0) after 1m0s
[15:11:03.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:55:0)
[15:11:03.033] Timer tick, asking Buildbucket for the build status
[15:11:03.121] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:03.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:56:0) after 1m0s
[15:12:03.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:56:0)
[15:12:03.127] Timer tick, asking Buildbucket for the build status
[15:12:03.246] Build 8858031100272094144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023461162297168208:57:0) after 1m0s
[15:12:29.242] Received PubSub notification, asking Buildbucket for the build status
[15:12:29.319] Build 8858031100272094144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:12:29.319] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610723548292489",
"created_by": "project:chromium",
"created_ts": "1610720267950883",
"id": "8858031100272094144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0d6de792a1d6c39624481bcfd60294c932726f5\"}}",
"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\": \"f0d6de792a1d6c39624481bcfd60294c932726f5\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16839, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"5678b56f5e2129615193034cd90bb6a0ef3c70d3\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"f0d6de792a1d6c39624481bcfd60294c932726f5\", \"got_revision_cp\": \"refs/heads/master@{#844052}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"f0d6de792a1d6c39624481bcfd60294c932726f5\", \"swarm_hashes_refs/heads/master(at){#844052}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"a8536340b02f3f23ed80a82666b517cec42d8233\", \"angle_unittests\": \"633b60da537bc4338e18cac8bd08a9aead46f663\", \"app_shell_unittests\": \"0c8621f730dc3a50d3b7dd791f5d7c1e0e930d94\", \"aura_unittests\": \"067a87ba197276b3239d5d0a78313126a93ff2c0\", \"base_unittests\": \"9e5eb22880835289504415857c02820773d85381\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"e4918a64b10e86003b20d4c97ac0954e90403047\", \"blink_fuzzer_unittests\": \"781ed51243d33208a13d31724b8af11b9508ca0b\", \"blink_heap_unittests\": \"07f4287cfd30f01e8314def4284e238713fcccde\", \"blink_platform_unittests\": \"ad60d54eae4ed01591194c6bafc62ecaf1eb6f4a\", \"blink_unittests\": \"146f35d2c999200683b6f3529ec72d2e4cc63917\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"40e279b68400b6e0806749fbfd25461b8eb46bd3\", \"capture_unittests\": \"25719c7a6fb7a23a98849493bb161df42768149a\", \"cast_unittests\": \"99a1272bfaeec54514ff01f820ab927ed8ec46ad\", \"cc_unittests\": \"72261b989a1c3ba5734a8cc83e89a9d40280a4eb\", \"chrome_app_unittests\": \"85fbb9416fcc029f770b5f7221de39dd3a0105e4\", \"chrome_cleaner_unittests\": \"60c80ee1fd45f9cb72722ea186978ff6a80d8514\", \"chrome_elf_unittests\": \"ef6b9973ebcd363ee142e8b3295f146582abd399\", \"chromedriver_unittests\": \"c0e3c45161f916450d40c1588d05dbc47640cbbb\", \"components_browsertests\": \"5b3e6decd94801757120b4897f270cffcd7f303b\", \"components_unittests\": \"5b5e7830adc124aa4593d5dae78f6c915c27ea38\", \"compositor_unittests\": \"8c3b41feb145ba676974aad55f67e064df3d30be\", \"content_browsertests\": \"f43eb3280152782b6a93e1aa2ec74a9726bd3c2a\", \"content_unittests\": \"0628684297fd00283c715d880d0688ee00850a39\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"002b430a417794ffae98e47832e4355ac354d4ce\", \"cronet_unittests\": \"043b0f0c7f37a21e12e99c73c280756808ad6079\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"81c228bd6920942e302d910e2ee895a7e098f218\", \"device_unittests\": \"02b265e36c379f14026c05764c0d1be676a49297\", \"display_unittests\": \"6777e0b6a92d147801d90e61d37fd44b1096f88b\", \"elevation_service_unittests\": \"a084b0d8a9f9f51494e45a64c819ab5dc7bacf01\", \"events_unittests\": \"0eb93cb71ff2717c1d39d5a00846220115f29173\", \"extensions_browsertests\": \"397d9fbe3b33891298b5d481688ba42da1504336\", \"extensions_unittests\": \"30caddbb86014a9f993adcae829d1e2a8dcf1982\", \"filesystem_service_unittests\": \"1a0a90ec0e5e56ae3e1106d44d642ed86eb571b7\", \"gcm_unit_tests\": \"a074ca76f8de2582d757343244ec1a6c6540711b\", \"gcp_unittests\": \"8f088dd5a5084e3dbdd9347907b80834e0ac323f\", \"gfx_unittests\": \"8d9fc8d1f1f70d9f89d102c73938e38c4af2cd1f\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"49100d51b9f4f4f2c9a3a893561d6f36f6ef2ef3\", \"gpu_unittests\": \"751ba6517619397149600a3cf737ded54c503790\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"3a3369ab1ca951fc2d108f0d21e4dfb718c6909e\", \"headless_unittests\": \"beed739fd31cb16e9f905260ee5db12772b9eaf7\", \"install_static_unittests\": \"d49b57e36d9f580b2207b3a09bab7dc64c1c508a\", \"installer_util_unittests\": \"c4c9eb6de794bc24dce05a2ff2591ae3d3d42885\", \"interactive_ui_tests\": \"085fb670816aef3783c0f59ed4b94f869c2176c7\", \"ipc_tests\": \"04b29ea2dcac58398dc589b81059bb615997fce1\", \"jingle_unittests\": \"36fd69b770404dcc6a40de0af27864991a14cb0e\", \"latency_unittests\": \"a52d62052f352647292a56f64c43126e68a233f8\", \"libjingle_xmpp_unittests\": \"24ec27fab270c2c07d1aae3a917d1d4592bd194f\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"06b54f585667fd36df50680a0c7695c531bd8f19\", \"media_unittests\": \"58be84320545b833f6ad29f6108449830b71aad5\", \"message_center_unittests\": \"b5e82d9e04fc6fc4827272d80b008b6e95304d14\", \"midi_unittests\": \"63ef9c56554fdcd74f5124fb04fc6dcc32676df1\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"6127ec5f62f8f0f55287a8b66dd93fc82624ee79\", \"nacl_loader_unittests\": \"33c8d586d95df3ffd594a3f20010f9d2d1d83479\", \"native_theme_unittests\": \"d027858932147252f7713e50b6dbac722a899b95\", \"net_unittests\": \"f0ba9bf80f7fdccd700c8b6a8a771bde30eac103\", \"notification_helper_unittests\": \"1f04d7d0e78b2519c0c027d9adf830aa2b611ac4\", \"pdf_unittests\": \"b6f4f9ddd88738786e06eb5b9b90a411abcdf64e\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"1d1e718c0826a073db69ce40e64cadac340c2375\", \"printing_unittests\": \"0f1e7f9302ac497c37050f316e63dc4301695d3d\", \"remoting_unittests\": \"0d1a124011a76bfd21cf2c4f9f14bc706014ddaa\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"de521c64743537dbee403d93eb92542657069db1\", \"services_unittests\": \"6087e0857a77469ecd0adbb1367773516d50cc93\", \"setup_unittests\": \"d322123ec3a521892de2c330838d7b713c690566\", \"shell_dialogs_unittests\": \"713166368cde9af6f63e3ecc53224191f3e4e01c\", \"skia_unittests\": \"819b1ea362c95a27279001f2bee92b9bd1eb2aee\", \"snapshot_unittests\": \"1834d4e5025f12edc1ad4a6360c15bdbcda4adf0\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"c2de7f025cb823dac151dcf45fd85c3be56d050d\", \"sync_integration_tests\": \"3998f6cde970aab709378062fc3a2c13ac834d85\", \"traffic_annotation_auditor_unittests\": \"5ca5b0dfb6e577335278e6a58d35ac57539b9360\", \"ui_base_unittests\": \"f67a038efa79372bddaba2bff171c9096d286139\", \"ui_touch_selection_unittests\": \"cecd6dd223efc860cb22d36109e47e5d30138fe0\", \"unit_tests\": \"d29d8c52bc30d68ba7672b8249b2e908a6117083\", \"updater_tests\": \"26c06d11bfb0614ad9b2677de37401c412b103f8\", \"url_unittests\": \"ca5c3c0079d3547037355463520ce302894f4548\", \"views_unittests\": \"9d3b158db4a6c61ef3d140a27cb972b9f457dfdd\", \"viz_unittests\": \"2ca1fcc2d4bd6ee4f73baf548dd9acd84e3d0b96\", \"vr_common_unittests\": \"42f01ad3a8b67677534c8491163668911b0aa3aa\", \"vr_pixeltests\": \"87c8495c75eebf2432bdea8a1f6839be23067fc8\", \"weblayer_browsertests\": \"00cfe9e672e065befabea4946ad8beb4862db936\", \"weblayer_unittests\": \"53411900663af1411f211e6e2fd657e917bb635a\", \"wm_unittests\": \"2ee0f6f14095cd0df17278e0e7bea140a51956b7\", \"wtf_unittests\": \"667e72065cc42bcb7533fe09e2c47e744b531281\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-nb2x\"], \"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\": [\"5666-679c9bd\"], \"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": "1610720270340186",
"status": "COMPLETED",
"status_changed_ts": "1610723548666654",
"tags": [
"build_address:luci.chromium.ci/win-asan/16839",
"builder:win-asan",
"buildset:commit/git/f0d6de792a1d6c39624481bcfd60294c932726f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0d6de792a1d6c39624481bcfd60294c932726f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023461162297168208",
"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/8858031100272094144/+/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:511f3a594352e110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610723548675383",
"url": "https://ci.chromium.org/b/8858031100272094144",
"utcnow_ts": "1610723549309896"
}
}
[15:12:29.319] Invocation finished in 54m42.684773043s with status SUCCEEDED