[01:18:40.160] New invocation is queued and will start shortly
[01:18:41.251] Starting the invocation (attempt 1)
[01:18:41.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:18:41.288] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:18:41.289] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028855400563646496",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"66f77941c8783625e388774285d988007ab253a0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028855400563646496",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/66f77941c8783625e388774285d988007ab253a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66f77941c8783625e388774285d988007ab253a0",
"gitiles_ref:refs/heads/master"
]
}
[01:18:41.979] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605575921381753",
"id": "8863425338620090112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66f77941c8783625e388774285d988007ab253a0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605575921860710",
"tags": [
"build_address:luci.chromium.ci/win-asan/15677",
"builder:win-asan",
"buildset:commit/git/66f77941c8783625e388774285d988007ab253a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66f77941c8783625e388774285d988007ab253a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028855400563646496",
"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/8863425338620090112/+/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": "1605575921860847",
"url": "https://ci.chromium.org/b/8863425338620090112",
"utcnow_ts": "1605575921971154"
}
}
[01:18:41.979] Task URL: https://ci.chromium.org/b/8863425338620090112
[01:18:41.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:2:0) after 1m0s
[01:18:43.731] Received PubSub notification, asking Buildbucket for the build status
[01:18:43.844] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:41.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:2:0)
[01:19:41.989] Timer tick, asking Buildbucket for the build status
[01:19:42.166] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:42.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:4:0) after 1m0s
[01:20:42.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:4:0)
[01:20:42.196] Timer tick, asking Buildbucket for the build status
[01:20:42.508] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:42.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:5:0) after 1m0s
[01:21:42.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:5:0)
[01:21:42.528] Timer tick, asking Buildbucket for the build status
[01:21:42.615] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:42.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:6:0) after 1m0s
[01:22:42.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:6:0)
[01:22:42.654] Timer tick, asking Buildbucket for the build status
[01:22:42.743] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:42.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:7:0) after 1m0s
[01:23:42.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:7:0)
[01:23:42.844] Timer tick, asking Buildbucket for the build status
[01:23:43.097] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:43.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:8:0) after 1m0s
[01:24:43.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:8:0)
[01:24:43.268] Timer tick, asking Buildbucket for the build status
[01:24:43.343] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:43.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:9:0) after 1m0s
[01:25:43.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:9:0)
[01:25:43.459] Timer tick, asking Buildbucket for the build status
[01:25:43.522] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:43.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:10:0) after 1m0s
[01:26:43.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:10:0)
[01:26:43.578] Timer tick, asking Buildbucket for the build status
[01:26:43.809] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:11:0) after 1m0s
[01:27:43.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:11:0)
[01:27:43.910] Timer tick, asking Buildbucket for the build status
[01:27:44.013] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:44.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:12:0) after 1m0s
[01:28:44.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:12:0)
[01:28:44.097] Timer tick, asking Buildbucket for the build status
[01:28:44.274] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:44.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:13:0) after 1m0s
[01:29:44.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:13:0)
[01:29:44.335] Timer tick, asking Buildbucket for the build status
[01:29:44.458] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:44.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:14:0) after 1m0s
[01:30:44.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:14:0)
[01:30:44.564] Timer tick, asking Buildbucket for the build status
[01:30:44.699] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:44.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:15:0) after 1m0s
[01:31:44.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:15:0)
[01:31:44.796] Timer tick, asking Buildbucket for the build status
[01:31:45.191] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:45.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:16:0) after 1m0s
[01:32:45.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:16:0)
[01:32:45.308] Timer tick, asking Buildbucket for the build status
[01:32:46.580] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:46.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:17:0) after 1m0s
[01:33:46.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:17:0)
[01:33:46.581] Timer tick, asking Buildbucket for the build status
[01:33:46.718] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:46.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:18:0) after 1m0s
[01:34:46.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:18:0)
[01:34:46.800] Timer tick, asking Buildbucket for the build status
[01:34:46.958] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:46.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:19:0) after 1m0s
[01:35:46.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:19:0)
[01:35:46.998] Timer tick, asking Buildbucket for the build status
[01:35:47.208] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:47.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:20:0) after 1m0s
[01:36:47.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:20:0)
[01:36:47.231] Timer tick, asking Buildbucket for the build status
[01:36:47.383] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:47.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:21:0) after 1m0s
[01:37:47.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:21:0)
[01:37:47.433] Timer tick, asking Buildbucket for the build status
[01:37:47.625] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:47.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:22:0) after 1m0s
[01:38:47.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:22:0)
[01:38:47.644] Timer tick, asking Buildbucket for the build status
[01:38:47.801] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:47.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:23:0) after 1m0s
[01:39:47.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:23:0)
[01:39:47.828] Timer tick, asking Buildbucket for the build status
[01:39:48.323] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:48.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:24:0) after 1m0s
[01:40:48.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:24:0)
[01:40:48.396] Timer tick, asking Buildbucket for the build status
[01:40:48.558] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:48.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:25:0) after 1m0s
[01:41:48.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:25:0)
[01:41:48.661] Timer tick, asking Buildbucket for the build status
[01:41:49.005] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:49.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:26:0) after 1m0s
[01:42:49.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:26:0)
[01:42:49.068] Timer tick, asking Buildbucket for the build status
[01:42:49.218] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:49.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:27:0) after 1m0s
[01:43:49.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:27:0)
[01:43:49.285] Timer tick, asking Buildbucket for the build status
[01:43:49.451] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:49.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:28:0) after 1m0s
[01:44:49.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:28:0)
[01:44:49.535] Timer tick, asking Buildbucket for the build status
[01:44:50.721] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:50.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:29:0) after 1m0s
[01:45:50.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:29:0)
[01:45:50.831] Timer tick, asking Buildbucket for the build status
[01:45:50.969] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:50.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:30:0) after 1m0s
[01:46:51.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:30:0)
[01:46:51.048] Timer tick, asking Buildbucket for the build status
[01:46:51.198] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:51.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:31:0) after 1m0s
[01:47:51.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:31:0)
[01:47:51.267] Timer tick, asking Buildbucket for the build status
[01:47:51.339] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:51.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:32:0) after 1m0s
[01:48:51.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:32:0)
[01:48:51.346] Timer tick, asking Buildbucket for the build status
[01:48:51.462] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:51.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:33:0) after 1m0s
[01:49:51.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:33:0)
[01:49:51.506] Timer tick, asking Buildbucket for the build status
[01:49:51.610] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:51.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:34:0) after 1m0s
[01:50:52.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:34:0)
[01:50:52.690] Timer tick, asking Buildbucket for the build status
[01:50:52.866] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:52.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:35:0) after 1m0s
[01:51:53.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:35:0)
[01:51:53.238] Timer tick, asking Buildbucket for the build status
[01:51:53.403] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:53.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:36:0) after 1m0s
[01:52:53.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:36:0)
[01:52:53.763] Timer tick, asking Buildbucket for the build status
[01:52:54.214] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:54.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:37:0) after 1m0s
[01:53:54.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:37:0)
[01:53:54.363] Timer tick, asking Buildbucket for the build status
[01:53:54.484] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:38:0) after 1m0s
[01:54:54.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:38:0)
[01:54:54.678] Timer tick, asking Buildbucket for the build status
[01:54:54.923] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:54.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:39:0) after 1m0s
[01:55:55.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:39:0)
[01:55:55.028] Timer tick, asking Buildbucket for the build status
[01:55:55.497] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:55.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:40:0) after 1m0s
[01:56:55.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:40:0)
[01:56:55.541] Timer tick, asking Buildbucket for the build status
[01:56:55.927] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:55.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:41:0) after 1m0s
[01:57:56.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:41:0)
[01:57:56.022] Timer tick, asking Buildbucket for the build status
[01:57:56.114] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:56.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:42:0) after 1m0s
[01:58:56.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:42:0)
[01:58:56.241] Timer tick, asking Buildbucket for the build status
[01:58:56.391] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:56.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:43:0) after 1m0s
[01:59:56.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:43:0)
[01:59:56.556] Timer tick, asking Buildbucket for the build status
[01:59:56.653] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:56.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:44:0) after 1m0s
[02:00:56.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:44:0)
[02:00:56.767] Timer tick, asking Buildbucket for the build status
[02:00:56.902] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:56.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:45:0) after 1m0s
[02:01:56.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:45:0)
[02:01:56.984] Timer tick, asking Buildbucket for the build status
[02:01:57.122] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:57.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:46:0) after 1m0s
[02:02:57.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:46:0)
[02:02:57.154] Timer tick, asking Buildbucket for the build status
[02:02:59.117] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:47:0) after 1m0s
[02:03:59.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:47:0)
[02:03:59.232] Timer tick, asking Buildbucket for the build status
[02:03:59.392] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:59.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:48:0) after 1m0s
[02:04:59.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:48:0)
[02:04:59.382] Timer tick, asking Buildbucket for the build status
[02:04:59.549] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:59.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:49:0) after 1m0s
[02:05:59.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:49:0)
[02:05:59.686] Timer tick, asking Buildbucket for the build status
[02:05:59.832] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:59.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:50:0) after 1m0s
[02:06:59.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:50:0)
[02:06:59.912] Timer tick, asking Buildbucket for the build status
[02:07:00.233] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:00.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:51:0) after 1m0s
[02:08:00.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:51:0)
[02:08:00.258] Timer tick, asking Buildbucket for the build status
[02:08:00.517] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:00.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:52:0) after 1m0s
[02:09:00.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:52:0)
[02:09:00.676] Timer tick, asking Buildbucket for the build status
[02:09:00.798] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:00.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:53:0) after 1m0s
[02:10:00.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:53:0)
[02:10:00.812] Timer tick, asking Buildbucket for the build status
[02:10:00.898] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:54:0) after 1m0s
[02:11:00.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:54:0)
[02:11:00.984] Timer tick, asking Buildbucket for the build status
[02:11:01.135] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:01.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:55:0) after 1m0s
[02:12:01.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:55:0)
[02:12:01.302] Timer tick, asking Buildbucket for the build status
[02:12:01.394] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:01.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:56:0) after 1m0s
[02:13:01.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:56:0)
[02:13:01.519] Timer tick, asking Buildbucket for the build status
[02:13:01.620] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:01.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:57:0) after 1m0s
[02:14:01.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:57:0)
[02:14:01.699] Timer tick, asking Buildbucket for the build status
[02:14:02.572] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:58:0) after 1m0s
[02:15:02.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:58:0)
[02:15:02.635] Timer tick, asking Buildbucket for the build status
[02:15:02.909] Build 8863425338620090112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028855400563646496:59:0) after 1m0s
[02:15:49.865] Received PubSub notification, asking Buildbucket for the build status
[02:15:49.971] Build 8863425338620090112: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:15:49.971] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605579347757219",
"created_by": "project:chromium",
"created_ts": "1605575921381753",
"failure_reason": "BUILD_FAILURE",
"id": "8863425338620090112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66f77941c8783625e388774285d988007ab253a0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed.\"}, \"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\": \"66f77941c8783625e388774285d988007ab253a0\", \"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-g9w4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15677, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"620cb770e3dd506be78103e362cfdab96fd2dca4\", \"got_dawn_revision\": \"2c5b040b5c2aa9482c7dc6a8dcbc730db21d5222\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"66f77941c8783625e388774285d988007ab253a0\", \"got_revision_cp\": \"refs/heads/master@{#828015}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"3e551b3ce67ddeaf0a29516b4dc98375886daf51\", \"got_v8_revision\": \"e05d24f98144a68f3f5d84b1dc1a9a28a0c7c804\", \"got_v8_revision_cp\": \"refs/heads/8.9.2@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"66f77941c8783625e388774285d988007ab253a0\", \"swarm_hashes_refs/heads/master(at){#828015}_without_patch\": {\"absl_hardening_tests\": \"a773d1a8c0826bea6668939a026fdfb5936076a1\", \"accessibility_unittests\": \"eab8b8dec5ededc23925f954624a5bac5d38a116\", \"angle_unittests\": \"9d1b0e6cd6a016395c384dab6441559653e0e30c\", \"app_shell_unittests\": \"076b0f9fd7a5e00b03c2acf9b0245ff636f7e76f\", \"aura_unittests\": \"d0c89d78ff6847ed5e5a5580da378b52cd64df51\", \"base_unittests\": \"88c1e95a523ecbd638d6ffb275a74351249f9a2c\", \"base_util_unittests\": \"d2238bf29b044fd38d08055f5da18314f31d10ae\", \"blink_common_unittests\": \"ec901151fffc1810ebeef4c08d97c2eedd5f6314\", \"blink_fuzzer_unittests\": \"9b70d2c7a7b8701f72b3a470a8656c9256ce184a\", \"blink_heap_unittests\": \"bbe148325400e36dd7b3e852f828709d116839bf\", \"blink_platform_unittests\": \"4059e94572c295e99a38500d27ba872ddd27a155\", \"blink_unittests\": \"dac6241464ce8b9d8cdd1085de8f1c3721772846\", \"boringssl_crypto_tests\": \"b3ec7a9c89837c757dcaeda67df9ddd679c23bc3\", \"boringssl_ssl_tests\": \"1c79f91b92d43673f2b6199c3ed306187daca15f\", \"browser_switcher_bho_unittests\": \"5b1644446c8d6b8033ad407b3eee665b36d8c7b6\", \"browser_tests\": \"d9053882daf823ed76458b00be83e534f945244b\", \"capture_unittests\": \"fa98ac1aa1ac4f8ca77a553b10b7a1b922fe0217\", \"cast_unittests\": \"5ed90efc67a4f6c9e1e8d9607ea0ec2b9d19a816\", \"cc_unittests\": \"d229e570df3cbe3cedbe5e71185919aac2f62404\", \"chrome_app_unittests\": \"1678b0cbf9039a42437d9e4c0cea74e8dd888d15\", \"chrome_cleaner_unittests\": \"dad84e481949cfef16e91a0613f84edd35a96375\", \"chrome_elf_unittests\": \"fa917bec1619007a851b34367cd54ae546c7b3d5\", \"chromedriver_unittests\": \"0abdda3784168851f7b1ea202e7d5240e92181b0\", \"components_browsertests\": \"85b073edafdda4c4760ad7a1c2cfaacf4a9c2389\", \"components_unittests\": \"9209bf13426711e28247462a23a28ca4be05e286\", \"compositor_unittests\": \"4530c01eecec92b823e8975430e44860d160ad0d\", \"content_browsertests\": \"011ca26c6daf8d180211ca7c3c76372abfda9813\", \"content_unittests\": \"08567f129ca113428232c27d45718ed24509ee1a\", \"courgette_unittests\": \"9c9d2192c3c30d2131c9fd361f0f3b4e553a1a71\", \"crashpad_tests\": \"1574292296c1c3aa085ed2b46952c0e4a96693eb\", \"cronet_tests\": \"e4ae647fd744372818adefc69447b9cf035efe78\", \"cronet_unittests\": \"de30e35ef62beb2abdd82c883e289cbd24ab6827\", \"crypto_unittests\": \"740452c1c56c0da4a56dcf2936499620d4c2b4ac\", \"delayloads_unittests\": \"e5e68238907690df87bcb0f193a112455c8abec6\", \"device_unittests\": \"cbf32a8bc463056ab0157d598bedb62f1ed5091c\", \"display_unittests\": \"052a85ce1304ca621bb17c7426cded9fd6019af8\", \"elevation_service_unittests\": \"57b9af0f52b8dfb223184f1d3e6d5fa510ba1ac3\", \"events_unittests\": \"a4758e250a7e1e7b304c96a8f95131ec91604c81\", \"extensions_browsertests\": \"828cb8cd0208792094ae3036a67c0075f3ca7eb0\", \"extensions_unittests\": \"42c4e1148ac872bf22363f0b474549a138d03515\", \"filesystem_service_unittests\": \"60c33b9ecb2e1294ee7f20a49561b055de7a9b61\", \"gcm_unit_tests\": \"17bf347e6c9a1ca370e00c825f55780a843080f9\", \"gcp_unittests\": \"00d8bdb45ab90bf0c8175da6aa1d6633e16a1e56\", \"gfx_unittests\": \"828dfc1bd6494f9d8497f63ce3452f68edfd4f7a\", \"gin_unittests\": \"21e2d5a6ceb4e27ebb80d52eb8cf958ed0985dad\", \"google_apis_unittests\": \"50d29bd36ca97faa93bebf6bffc5ad0bf77e85cc\", \"gpu_unittests\": \"afcf22ed0c1789c55e59694ea6386448419d5c67\", \"gwp_asan_unittests\": \"edcea38c9145d7751c7c228256b8d8d5f1563468\", \"headless_browsertests\": \"531e6999f264c09ee27c1e4b3eb40aea747571e7\", \"headless_unittests\": \"d0b0b8c10193812bf465ec43d2ef6a2e20b1ce02\", \"install_static_unittests\": \"b2d3aa4b401706013a67cf46f03f97d132049958\", \"installer_util_unittests\": \"e3d5e56dfe7fd93dad04a0fb4a833dc6d09b49dd\", \"interactive_ui_tests\": \"0d0cd6e9bd690b1a99a49eb9901e94c43632516d\", \"ipc_tests\": \"7c09864f136c1de680ae9440be0db1c886a53f9d\", \"jingle_unittests\": \"c946f3ccdffa9db5a73720184211279198c55995\", \"latency_unittests\": \"0cb3fb7d44c21b0066fd69acdd3df1612a1b83c2\", \"libjingle_xmpp_unittests\": \"0da358ee6755f7e75d618bc07843ee534c3f7537\", \"liburlpattern_unittests\": \"ef65e34a58b90adace0f50ffa2ed5081ff14c9c3\", \"media_blink_unittests\": \"79c78a45bdeaf7d116bcb2b3c3fac9217c9b94b3\", \"media_unittests\": \"5c44b90c4d9a1f77a9c491d3fc6ae6f85e364790\", \"message_center_unittests\": \"6d1c49e885af2ee98a2a4e049eb4469200f3a95a\", \"midi_unittests\": \"48d9f4a002d2ef244fb14fef422232070e974302\", \"mojo_core_unittests\": \"54f90cd64d50d1b8b9d56eb0517e889d1a916dc3\", \"mojo_unittests\": \"2292d8d015fce04a6a4786a32c0f75096bd5ea4f\", \"nacl_loader_unittests\": \"15bb3029313b3f5cf913e43a70853a737470be47\", \"native_theme_unittests\": \"9fb07fed36aef82b991492c1ae7df79ddf81d038\", \"net_unittests\": \"633843a90df520749a6996ae3f66e8ac1cd70419\", \"notification_helper_unittests\": \"670b6dd8820e28e7d44d9ee1b8d69d2fc50b5ce1\", \"pdf_unittests\": \"333b8c4eea56f31f6948d5893f9e65eaa17732d9\", \"perfetto_unittests\": \"6fe87fea85c37af581a91b1277dffe98d3e669cb\", \"ppapi_unittests\": \"6f49a67ba02cf0b0f4b7d1920222023f57f8082a\", \"printing_unittests\": \"ebc8a069a1ddb5a4c5bbca3e9498ebff72038535\", \"remoting_unittests\": \"2f7331263d47077a33bc062ca195951a19922843\", \"sbox_integration_tests\": \"bf91f9a6bcb9e2534336bebaead84c577efb2a0a\", \"sbox_unittests\": \"63eb50860dbbea145c1cea388f79faec01e298e1\", \"sbox_validation_tests\": \"8219b226354b88ccaec9d6b2c5e9c9f503e8da20\", \"service_manager_unittests\": \"126f63ccd12672e5e1dc760ffa27a2c8609a4905\", \"services_unittests\": \"b1c761cc94b93a466d6fe8ae4302b8e88c4e8c47\", \"setup_unittests\": \"d63824b063349658c61ce1c94a3f5f882e1a2405\", \"shell_dialogs_unittests\": \"d43918f927aed644d428dbdca32fe3b17b8c601c\", \"skia_unittests\": \"ced70b3d5671e803e1233b847c1e67e40eab3f7f\", \"snapshot_unittests\": \"5d9989a8a5e97ac514ba7ce63abdb0dc567021f0\", \"sql_unittests\": \"66f640a68fb73beca8912091a65108c899da493c\", \"storage_unittests\": \"25cf274dc8f540bf16bff9ef61333c3542befacd\", \"sync_integration_tests\": \"a0f2ea8e6d908847feb14d7cffd220b35a30d24f\", \"traffic_annotation_auditor_unittests\": \"41bb6643464afb54091fb0e20157597394b6853e\", \"ui_base_unittests\": \"1bf62cfd398b820db98c7dbeafcab526c2d78a7d\", \"ui_touch_selection_unittests\": \"95cb3d13578cccb1bcb92e6ce8516ed4dd572a66\", \"unit_tests\": \"a131d6d874a420ac2218c4d04ae371bfedd364ef\", \"updater_tests\": \"22ea20abd5db0b399c20c8725dde9619e862564c\", \"url_unittests\": \"c2d5432404d6025887c9d1c132cfdb33ef694174\", \"views_unittests\": \"59bc632543dbc72144a38a879e456287105391ba\", \"viz_unittests\": \"7792991d48482707a67573663c71f7d7acd3bc74\", \"vr_common_unittests\": \"4a6be332d42a2991a70760c3693cdbd951efe8e2\", \"vr_pixeltests\": \"33c877e0e9a64767a789718205cb9a4cbe5ee71c\", \"weblayer_browsertests\": \"29d749f1b235650c139d5d0a6875f7930b7a942d\", \"weblayer_unittests\": \"a12f000944959ab019211ddab11fe3e205414585\", \"wm_unittests\": \"ef7a385ff4eff590fdd0272f80d9571909a4fbcc\", \"wtf_unittests\": \"4ac77913178c27fa556df917be9194d05400405c\", \"zlib_unittests\": \"0474bd01afd2c6094b4b9275258061bbaa200033\", \"zucchini_unittests\": \"eb387151cdddd0229e3190963e3b90f70d3e1c1c\"}}, \"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-g9w4\"], \"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\": [\"5632-2e614e1\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605575922951704",
"status": "COMPLETED",
"status_changed_ts": "1605575923237658",
"tags": [
"build_address:luci.chromium.ci/win-asan/15677",
"builder:win-asan",
"buildset:commit/git/66f77941c8783625e388774285d988007ab253a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66f77941c8783625e388774285d988007ab253a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028855400563646496",
"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/8863425338620090112/+/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:4fec99d9962b9210",
"user_agent:luci-scheduler"
],
"updated_ts": "1605579349342174",
"url": "https://ci.chromium.org/b/8863425338620090112",
"utcnow_ts": "1605579349946122"
}
}
[02:15:49.971] Invocation finished in 57m9.827036743s with status FAILED