[02:25:57.449] New invocation is queued and will start shortly
[02:25:58.848] Starting the invocation (attempt 1)
[02:25:58.893] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:25:58.893] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:25:58.893] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024049527939659584",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"90e072a11de3c17c8566c97d5325f65bf4a7dc36\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024049527939659584",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"gitiles_ref:refs/heads/master"
]
}
[02:25:59.684] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1610159158971958",
"id": "8858619465681212416",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90e072a11de3c17c8566c97d5325f65bf4a7dc36\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610159159439315",
"tags": [
"build_address:luci.chromium.ci/win-asan/16704",
"builder:win-asan",
"buildset:commit/git/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024049527939659584",
"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/8858619465681212416/+/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": "1610159159440472",
"url": "https://ci.chromium.org/b/8858619465681212416",
"utcnow_ts": "1610159159634824"
}
}
[02:25:59.684] Task URL: https://ci.chromium.org/b/8858619465681212416
[02:25:59.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:2:0) after 1m0s
[02:26:06.160] Received PubSub notification, asking Buildbucket for the build status
[02:26:06.326] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:59.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:2:0)
[02:26:59.717] Timer tick, asking Buildbucket for the build status
[02:27:00.130] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:00.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:4:0) after 1m0s
[02:28:00.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:4:0)
[02:28:00.250] Timer tick, asking Buildbucket for the build status
[02:28:00.369] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:00.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:5:0) after 1m0s
[02:29:00.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:5:0)
[02:29:00.383] Timer tick, asking Buildbucket for the build status
[02:29:00.599] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:00.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:6:0) after 1m0s
[02:30:00.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:6:0)
[02:30:00.612] Timer tick, asking Buildbucket for the build status
[02:30:00.775] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:00.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:7:0) after 1m0s
[02:31:01.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:7:0)
[02:31:01.183] Timer tick, asking Buildbucket for the build status
[02:31:01.318] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:01.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:8:0) after 1m0s
[02:32:01.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:8:0)
[02:32:01.597] Timer tick, asking Buildbucket for the build status
[02:32:01.677] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:01.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:9:0) after 1m0s
[02:33:01.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:9:0)
[02:33:01.722] Timer tick, asking Buildbucket for the build status
[02:33:01.798] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:01.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:10:0) after 1m0s
[02:34:01.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:10:0)
[02:34:01.836] Timer tick, asking Buildbucket for the build status
[02:34:01.914] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:01.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:11:0) after 1m0s
[02:35:01.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:11:0)
[02:35:01.988] Timer tick, asking Buildbucket for the build status
[02:35:02.145] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:02.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:12:0) after 1m0s
[02:36:02.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:12:0)
[02:36:02.165] Timer tick, asking Buildbucket for the build status
[02:36:02.508] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:02.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:13:0) after 1m0s
[02:37:02.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:13:0)
[02:37:02.526] Timer tick, asking Buildbucket for the build status
[02:37:02.950] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:02.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:14:0) after 1m0s
[02:38:03.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:14:0)
[02:38:03.069] Timer tick, asking Buildbucket for the build status
[02:38:03.162] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:03.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:15:0) after 1m0s
[02:39:03.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:15:0)
[02:39:03.267] Timer tick, asking Buildbucket for the build status
[02:39:03.554] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:03.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:16:0) after 1m0s
[02:40:03.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:16:0)
[02:40:03.595] Timer tick, asking Buildbucket for the build status
[02:40:03.735] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:03.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:17:0) after 1m0s
[02:41:03.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:17:0)
[02:41:03.798] Timer tick, asking Buildbucket for the build status
[02:41:04.304] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:04.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:18:0) after 1m0s
[02:42:04.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:18:0)
[02:42:04.322] Timer tick, asking Buildbucket for the build status
[02:42:04.987] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:19:0) after 1m0s
[02:43:05.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:19:0)
[02:43:05.008] Timer tick, asking Buildbucket for the build status
[02:43:05.252] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:05.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:20:0) after 1m0s
[02:44:05.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:20:0)
[02:44:05.259] Timer tick, asking Buildbucket for the build status
[02:44:05.512] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:05.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:21:0) after 1m0s
[02:45:05.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:21:0)
[02:45:05.586] Timer tick, asking Buildbucket for the build status
[02:45:05.720] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:05.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:22:0) after 1m0s
[02:46:05.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:22:0)
[02:46:05.759] Timer tick, asking Buildbucket for the build status
[02:46:05.970] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:05.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:23:0) after 1m0s
[02:47:06.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:23:0)
[02:47:06.016] Timer tick, asking Buildbucket for the build status
[02:47:06.596] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:06.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:24:0) after 1m0s
[02:48:06.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:24:0)
[02:48:06.667] Timer tick, asking Buildbucket for the build status
[02:48:06.808] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:06.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:25:0) after 1m0s
[02:49:06.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:25:0)
[02:49:06.860] Timer tick, asking Buildbucket for the build status
[02:49:07.065] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:07.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:26:0) after 1m0s
[02:50:07.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:26:0)
[02:50:07.098] Timer tick, asking Buildbucket for the build status
[02:50:07.368] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:07.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:27:0) after 1m0s
[02:51:07.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:27:0)
[02:51:07.395] Timer tick, asking Buildbucket for the build status
[02:51:07.523] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:07.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:28:0) after 1m0s
[02:52:07.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:28:0)
[02:52:07.494] Timer tick, asking Buildbucket for the build status
[02:52:07.628] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:07.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:29:0) after 1m0s
[02:53:07.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:29:0)
[02:53:07.665] Timer tick, asking Buildbucket for the build status
[02:53:07.746] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:07.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:30:0) after 1m0s
[02:54:07.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:30:0)
[02:54:07.792] Timer tick, asking Buildbucket for the build status
[02:54:07.869] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:07.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:31:0) after 1m0s
[02:55:07.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:31:0)
[02:55:07.889] Timer tick, asking Buildbucket for the build status
[02:55:08.038] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:08.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:32:0) after 1m0s
[02:56:08.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:32:0)
[02:56:08.061] Timer tick, asking Buildbucket for the build status
[02:56:08.172] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:08.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:33:0) after 1m0s
[02:57:08.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:33:0)
[02:57:08.217] Timer tick, asking Buildbucket for the build status
[02:57:09.424] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:09.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:34:0) after 1m0s
[02:58:09.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:34:0)
[02:58:09.504] Timer tick, asking Buildbucket for the build status
[02:58:09.594] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:09.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:35:0) after 1m0s
[02:59:09.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:35:0)
[02:59:09.573] Timer tick, asking Buildbucket for the build status
[02:59:09.704] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:09.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:36:0) after 1m0s
[03:00:09.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:36:0)
[03:00:09.794] Timer tick, asking Buildbucket for the build status
[03:00:09.889] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:09.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:37:0) after 1m0s
[03:01:09.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:37:0)
[03:01:09.945] Timer tick, asking Buildbucket for the build status
[03:01:10.396] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:10.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:38:0) after 1m0s
[03:02:10.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:38:0)
[03:02:10.437] Timer tick, asking Buildbucket for the build status
[03:02:10.712] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:10.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:39:0) after 1m0s
[03:03:10.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:39:0)
[03:03:10.820] Timer tick, asking Buildbucket for the build status
[03:03:10.931] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:10.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:40:0) after 1m0s
[03:04:10.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:40:0)
[03:04:10.942] Timer tick, asking Buildbucket for the build status
[03:04:11.055] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:11.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:41:0) after 1m0s
[03:05:11.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:41:0)
[03:05:11.108] Timer tick, asking Buildbucket for the build status
[03:05:11.213] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:11.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:42:0) after 1m0s
[03:06:11.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:42:0)
[03:06:11.274] Timer tick, asking Buildbucket for the build status
[03:06:11.403] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:11.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:43:0) after 1m0s
[03:07:11.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:43:0)
[03:07:11.416] Timer tick, asking Buildbucket for the build status
[03:07:11.545] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:11.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:44:0) after 1m0s
[03:08:11.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:44:0)
[03:08:11.600] Timer tick, asking Buildbucket for the build status
[03:08:11.670] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:11.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:45:0) after 1m0s
[03:09:11.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:45:0)
[03:09:11.745] Timer tick, asking Buildbucket for the build status
[03:09:11.851] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:11.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:46:0) after 1m0s
[03:10:12.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:46:0)
[03:10:12.007] Timer tick, asking Buildbucket for the build status
[03:10:12.392] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:12.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:47:0) after 1m0s
[03:11:12.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:47:0)
[03:11:12.531] Timer tick, asking Buildbucket for the build status
[03:11:12.740] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:12.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:48:0) after 1m0s
[03:12:12.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:48:0)
[03:12:12.864] Timer tick, asking Buildbucket for the build status
[03:12:13.505] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:13.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:49:0) after 1m0s
[03:13:13.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:49:0)
[03:13:13.528] Timer tick, asking Buildbucket for the build status
[03:13:13.594] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:13.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:50:0) after 1m0s
[03:14:13.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:50:0)
[03:14:13.626] Timer tick, asking Buildbucket for the build status
[03:14:13.707] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:13.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:51:0) after 1m0s
[03:15:13.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:51:0)
[03:15:13.743] Timer tick, asking Buildbucket for the build status
[03:15:13.845] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:13.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:52:0) after 1m0s
[03:16:13.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:52:0)
[03:16:13.873] Timer tick, asking Buildbucket for the build status
[03:16:14.103] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:14.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:53:0) after 1m0s
[03:17:14.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:53:0)
[03:17:14.149] Timer tick, asking Buildbucket for the build status
[03:17:14.210] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:14.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:54:0) after 1m0s
[03:18:14.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:54:0)
[03:18:14.329] Timer tick, asking Buildbucket for the build status
[03:18:14.580] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:14.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:55:0) after 1m0s
[03:19:14.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:55:0)
[03:19:14.696] Timer tick, asking Buildbucket for the build status
[03:19:14.762] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:14.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:56:0) after 1m0s
[03:20:14.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:56:0)
[03:20:14.795] Timer tick, asking Buildbucket for the build status
[03:20:14.866] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:14.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:57:0) after 1m0s
[03:21:15.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:57:0)
[03:21:15.011] Timer tick, asking Buildbucket for the build status
[03:21:15.141] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:15.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:58:0) after 1m0s
[03:22:15.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:58:0)
[03:22:15.264] Timer tick, asking Buildbucket for the build status
[03:22:15.344] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:15.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:59:0) after 1m0s
[03:23:15.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:59:0)
[03:23:15.449] Timer tick, asking Buildbucket for the build status
[03:23:15.798] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:60:0) after 1m0s
[03:24:15.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:60:0)
[03:24:15.950] Timer tick, asking Buildbucket for the build status
[03:24:16.709] Build 8858619465681212416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:16.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024049527939659584:61:0) after 1m0s
[03:24:40.636] Received PubSub notification, asking Buildbucket for the build status
[03:24:40.708] Build 8858619465681212416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:24:40.708] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1610162679679086",
"created_by": "project:chromium",
"created_ts": "1610159158971958",
"id": "8858619465681212416",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90e072a11de3c17c8566c97d5325f65bf4a7dc36\"}}",
"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\": \"90e072a11de3c17c8566c97d5325f65bf4a7dc36\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16704, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"038ae1448ac13b1c43b9ce7bcc5040c45206668a\", \"got_dawn_revision\": \"c9a22bcb93db27e200a184c1f7b9bf0f288815c7\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"90e072a11de3c17c8566c97d5325f65bf4a7dc36\", \"got_revision_cp\": \"refs/heads/master@{#841760}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"588d32cb3f172d1a6092c2b3c7e5a5c5d8b3b06e\", \"got_v8_revision\": \"6bb19707a491fda7a4dc04dbbeb188d48f6d641d\", \"got_v8_revision_cp\": \"refs/heads/8.9.225@{#1}\", \"got_webrtc_revision\": \"644164c9c4b368d96bde2b3dcce68c57c2b7f749\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32926}\", \"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\": \"90e072a11de3c17c8566c97d5325f65bf4a7dc36\", \"swarm_hashes_refs/heads/master(at){#841760}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"aaf2fdaf6c9c9d8886f92c9bb563dc3d7df2f7e8\", \"angle_unittests\": \"796778c76ceea344ede38f94d5c4c36c01dacfdb\", \"app_shell_unittests\": \"ddba07a95787c8e81f0877c19c6a9dc35b6356f7\", \"aura_unittests\": \"a4cee97d949faf0f338f45efb0c89e3125ba1078\", \"base_unittests\": \"7db0c3ac9d2048812b71f7f3d54848964aa83aa7\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"2baaff288d29c8cd0aa6d18d24392c316927dddf\", \"blink_fuzzer_unittests\": \"bff333a57964a9629c650a9314b6169d9a152e7b\", \"blink_heap_unittests\": \"2e0e789ffe216d8cc1eb6f7700656ee929ba7d4f\", \"blink_platform_unittests\": \"177cae734418287426134edad733d5ff273516b2\", \"blink_unittests\": \"527197f30d6bfa1ce0e91f6a0164db4983493df8\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"608d0f5fc62d2f9f55201234652dc7f08bdf3a9b\", \"capture_unittests\": \"fb893fe6ad8a3693c2f4a71343d934372758b534\", \"cast_unittests\": \"0c9ac4b962fd78d5d11db647eb6ebc478efe4c88\", \"cc_unittests\": \"5dad7a8d23f41640ee02da75dd6083bbcad2e201\", \"chrome_app_unittests\": \"c0a2cc94d04fc3ac82180938383dbcc0f3a76ca2\", \"chrome_cleaner_unittests\": \"90f48bd7c221d459ae1899176298d91946c101e8\", \"chrome_elf_unittests\": \"d5b81aa6e60a55fd1e124ded23660f9d72454479\", \"chromedriver_unittests\": \"9788c189d5fb3ad1ec26d6bdeda2d45d404b3bbb\", \"components_browsertests\": \"c6e65ad670929427a891aac3d171762cf27232a1\", \"components_unittests\": \"63f4c2154148a7461a66643d8a73b1326f9a405b\", \"compositor_unittests\": \"c0757b48501c8b0b145f63d3a1611c9a89d9e87e\", \"content_browsertests\": \"ae00f9f56a605c853bd1573926c3639f2c9c62f6\", \"content_unittests\": \"842f7d2bba2a80d837d2278ad4cb3911d7c78ae8\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"f380695b8ac6bb59accba727ffd2f36f4012a582\", \"cronet_unittests\": \"80ce1d9063738c7f180a36a24976b6db0909f0d0\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"25a2ef5aa5179f4d228fc80f62e95b5e68e30f3f\", \"device_unittests\": \"62bb37077fe3a33b5c1d261fada70790e51f1679\", \"display_unittests\": \"9ebcd35c895c7609cbc4787e0fbc562871e21a3a\", \"elevation_service_unittests\": \"e4b0952a22ebc5fd7f8f5af98a289e553a2be18d\", \"events_unittests\": \"c20d25ed9d8277118902a4a77a4a203bb620ef57\", \"extensions_browsertests\": \"3e84ecd0c39bfe23138bfe3583c447820e424f09\", \"extensions_unittests\": \"631b53dcadf799be3b6a7a0e554326e2fbc9e713\", \"filesystem_service_unittests\": \"e864c2e6bd97465efc656472cd4e50e1709a411a\", \"gcm_unit_tests\": \"1278c388cfc9afacfaeca61ca797a28bb996837d\", \"gcp_unittests\": \"532adfed56434465448b8c81e5cf45e69b6b96a3\", \"gfx_unittests\": \"a540de36232f5fa0db4a9a319c3784668dd06cdf\", \"gin_unittests\": \"72397f2c615b6dafd54d8d773ce1c45deb7eca5e\", \"google_apis_unittests\": \"100bfbc8f31539028dbe08b29afadae96c248750\", \"gpu_unittests\": \"649bb07c712c8922d63d63fdb38d4372c52922a5\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"c2733c1c4e904ac08e0ef7aa0cc51c59451a1df1\", \"headless_unittests\": \"acde683ba191b8fb7e969e574fcdc6fd72136ef2\", \"install_static_unittests\": \"8a6e289562d720c2b99072836d8daf5f28c6783a\", \"installer_util_unittests\": \"5dfa1241ea3171b914c83111306b022c5c7f3bc4\", \"interactive_ui_tests\": \"4eb13724f7971fc0a55953cb6a440999f05e919e\", \"ipc_tests\": \"84aa95b285e641645e6f3c3f4b16ede3105cf596\", \"jingle_unittests\": \"94626e3f6b19b1e4b6e145e9e2dadf86464d722f\", \"latency_unittests\": \"3ff8c6465b79c6ddfb6baad488c9a70b3ade1962\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"0ae7494a33b112b47be2f8bae9c584e11b064cd9\", \"media_unittests\": \"2d74925e5a94b0389f5a4cf05d826637e8290534\", \"message_center_unittests\": \"d3362cd22e0f59054f01e86945865faf93467af3\", \"midi_unittests\": \"8d60036e409c9421327a1d7c023cf0539b87ddfd\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"643d047b89d425f557ac9aee8a5983fdf1fc0662\", \"nacl_loader_unittests\": \"447b71d20465218da6ae49a28cf5ab153d00c5ba\", \"native_theme_unittests\": \"40b2ede3ffba059a3b381368730abd3a061f8bf5\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"b51a8824f938689cee7510ded811aa4b01a9c8ca\", \"pdf_unittests\": \"af4d02701ea2a079f972654b30de37f8658e9473\", \"perfetto_unittests\": \"a9947a0f3b5f34108cf32cdc1f91cbad7f96c405\", \"ppapi_unittests\": \"634e0a763bbdc86988a5b3b56f2b6d06c790aff3\", \"printing_unittests\": \"805ad16f11007f3d5fddc3f509dcb6ba71a30460\", \"remoting_unittests\": \"64704da34310c03fc43f1f7a87315eed64ba38e3\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"f527fcf7cdc64fe0309e243403925a63ba6d9efa\", \"services_unittests\": \"4c049e0a4c1999af0bb7dc6cfc634c4e767c6ee9\", \"setup_unittests\": \"b2694d44d6aaa6560b53965413fbb13ca959a915\", \"shell_dialogs_unittests\": \"065916b93054cb91f58a0f519a744b191a6b4d29\", \"skia_unittests\": \"4766ecf959ad936045d16864652117d0594793c2\", \"snapshot_unittests\": \"e9cb67b60d1d8b9f7178176daac6c3b2c4d84b46\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"2ca2ceb381c7511aca1ec9bcbf2fb2a0e2bfd611\", \"sync_integration_tests\": \"bfabc0ad27c418fc22a4c493c942879113005af9\", \"traffic_annotation_auditor_unittests\": \"e4ef3d83d5430f29691854866a83519ac22cfbe7\", \"ui_base_unittests\": \"aa6bbe7852e8f39a8f31772d0041ae68aae08684\", \"ui_touch_selection_unittests\": \"c8b97148a2ba6149954b5d9bb6cb1af49554cad5\", \"unit_tests\": \"3512569c409b2c8e21333e39f6af39def549d13c\", \"updater_tests\": \"9fdb07df45354782ecb94741fe54a9ea72f720c1\", \"url_unittests\": \"0f520f8caf55002184eee315d13938c8afa1a2d8\", \"views_unittests\": \"77f078883348dca6d4dacd10fd9d1bf98649340f\", \"viz_unittests\": \"02c754cb2bf5dd0d2eb0cd141971b268383a87cc\", \"vr_common_unittests\": \"212932304b0879be7f3386ede878138387f2d9a9\", \"vr_pixeltests\": \"63495181b9ea0fec0d8fad90fb07392fe9be27ab\", \"weblayer_browsertests\": \"d275f7c3f91e7a0034a5231b92433ed740137193\", \"weblayer_unittests\": \"4e25cc4ae050a99ab731f187f39cb19354330316\", \"wm_unittests\": \"e9831552969099a9fbff1e458b4c4f61b7ef0304\", \"wtf_unittests\": \"0c59e236ac51d0aee1a1135b56ed9dc2fd224331\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"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-7-w73f\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"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": "1610159161330432",
"status": "COMPLETED",
"status_changed_ts": "1610162680142595",
"tags": [
"build_address:luci.chromium.ci/win-asan/16704",
"builder:win-asan",
"buildset:commit/git/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90e072a11de3c17c8566c97d5325f65bf4a7dc36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024049527939659584",
"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/8858619465681212416/+/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:50fdc881b4fc2410",
"user_agent:luci-scheduler"
],
"updated_ts": "1610162680152444",
"url": "https://ci.chromium.org/b/8858619465681212416",
"utcnow_ts": "1610162680697021"
}
}
[03:24:40.708] Invocation finished in 58m43.27382301s with status SUCCEEDED