[01:25:16.240] New invocation is queued and will start shortly
[01:25:17.296] Starting the invocation (attempt 1)
[01:25:17.340] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:25:17.340] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:25:17.340] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026952448950027856",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"45b34f613273af9df5b49a5939e8b67e4420a90f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026952448950027856",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/45b34f613273af9df5b49a5939e8b67e4420a90f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b34f613273af9df5b49a5939e8b67e4420a90f",
"gitiles_ref:refs/heads/master"
]
}
[01:25:18.907] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607390718261826",
"id": "8861522386167400992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"45b34f613273af9df5b49a5939e8b67e4420a90f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607390718608098",
"tags": [
"build_address:luci.chromium.ci/win-asan/16092",
"builder:win-asan",
"buildset:commit/git/45b34f613273af9df5b49a5939e8b67e4420a90f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b34f613273af9df5b49a5939e8b67e4420a90f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026952448950027856",
"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/8861522386167400992/+/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": "1607390718608273",
"url": "https://ci.chromium.org/b/8861522386167400992",
"utcnow_ts": "1607390718899415"
}
}
[01:25:18.907] Task URL: https://ci.chromium.org/b/8861522386167400992
[01:25:18.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:2:0) after 1m0s
[01:25:22.170] Received PubSub notification, asking Buildbucket for the build status
[01:25:22.273] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:19.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:2:0)
[01:26:19.002] Timer tick, asking Buildbucket for the build status
[01:26:19.276] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:19.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:4:0) after 1m0s
[01:27:19.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:4:0)
[01:27:19.334] Timer tick, asking Buildbucket for the build status
[01:27:19.470] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:19.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:5:0) after 1m0s
[01:28:19.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:5:0)
[01:28:19.542] Timer tick, asking Buildbucket for the build status
[01:28:19.734] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:19.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:6:0) after 1m0s
[01:29:19.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:6:0)
[01:29:19.757] Timer tick, asking Buildbucket for the build status
[01:29:19.882] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:19.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:7:0) after 1m0s
[01:30:19.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:7:0)
[01:30:19.976] Timer tick, asking Buildbucket for the build status
[01:30:20.501] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:20.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:8:0) after 1m0s
[01:31:20.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:8:0)
[01:31:20.604] Timer tick, asking Buildbucket for the build status
[01:31:20.686] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:20.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:9:0) after 1m0s
[01:32:20.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:9:0)
[01:32:20.822] Timer tick, asking Buildbucket for the build status
[01:32:20.872] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:20.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:10:0) after 1m0s
[01:33:20.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:10:0)
[01:33:20.929] Timer tick, asking Buildbucket for the build status
[01:33:21.018] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:21.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:11:0) after 1m0s
[01:34:23.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:11:0)
[01:34:23.605] Timer tick, asking Buildbucket for the build status
[01:34:23.684] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:23.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:12:0) after 1m0s
[01:35:24.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:12:0)
[01:35:24.343] Timer tick, asking Buildbucket for the build status
[01:35:24.441] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:24.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:13:0) after 1m0s
[01:36:24.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:13:0)
[01:36:24.482] Timer tick, asking Buildbucket for the build status
[01:36:24.601] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:24.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:14:0) after 1m0s
[01:37:24.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:14:0)
[01:37:24.681] Timer tick, asking Buildbucket for the build status
[01:37:24.761] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:24.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:15:0) after 1m0s
[01:38:24.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:15:0)
[01:38:24.741] Timer tick, asking Buildbucket for the build status
[01:38:24.946] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:24.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:16:0) after 1m0s
[01:39:25.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:16:0)
[01:39:25.047] Timer tick, asking Buildbucket for the build status
[01:39:25.564] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:25.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:17:0) after 1m0s
[01:40:25.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:17:0)
[01:40:25.612] Timer tick, asking Buildbucket for the build status
[01:40:25.737] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:25.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:18:0) after 1m0s
[01:41:25.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:18:0)
[01:41:25.760] Timer tick, asking Buildbucket for the build status
[01:41:25.864] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:25.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:19:0) after 1m0s
[01:42:26.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:19:0)
[01:42:26.078] Timer tick, asking Buildbucket for the build status
[01:42:26.416] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:26.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:20:0) after 1m0s
[01:43:26.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:20:0)
[01:43:26.551] Timer tick, asking Buildbucket for the build status
[01:43:26.625] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:26.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:21:0) after 1m0s
[01:44:26.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:21:0)
[01:44:26.798] Timer tick, asking Buildbucket for the build status
[01:44:26.968] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:26.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:22:0) after 1m0s
[01:45:27.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:22:0)
[01:45:27.061] Timer tick, asking Buildbucket for the build status
[01:45:27.231] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:27.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:23:0) after 1m0s
[01:46:27.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:23:0)
[01:46:27.560] Timer tick, asking Buildbucket for the build status
[01:46:27.702] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:27.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:24:0) after 1m0s
[01:47:27.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:24:0)
[01:47:27.741] Timer tick, asking Buildbucket for the build status
[01:47:28.103] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:25:0) after 1m0s
[01:48:28.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:25:0)
[01:48:28.152] Timer tick, asking Buildbucket for the build status
[01:48:28.273] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:28.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:26:0) after 1m0s
[01:49:28.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:26:0)
[01:49:28.258] Timer tick, asking Buildbucket for the build status
[01:49:28.372] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:28.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:27:0) after 1m0s
[01:50:28.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:27:0)
[01:50:28.392] Timer tick, asking Buildbucket for the build status
[01:50:28.513] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:28.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:28:0) after 1m0s
[01:51:28.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:28:0)
[01:51:28.540] Timer tick, asking Buildbucket for the build status
[01:51:28.966] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:28.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:29:0) after 1m0s
[01:52:29.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:29:0)
[01:52:29.112] Timer tick, asking Buildbucket for the build status
[01:52:29.208] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:29.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:30:0) after 1m0s
[01:53:29.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:30:0)
[01:53:29.230] Timer tick, asking Buildbucket for the build status
[01:53:29.418] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:29.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:31:0) after 1m0s
[01:54:29.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:31:0)
[01:54:29.469] Timer tick, asking Buildbucket for the build status
[01:54:29.572] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:29.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:32:0) after 1m0s
[01:55:29.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:32:0)
[01:55:29.605] Timer tick, asking Buildbucket for the build status
[01:55:29.734] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:29.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:33:0) after 1m0s
[01:56:29.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:33:0)
[01:56:29.766] Timer tick, asking Buildbucket for the build status
[01:56:30.157] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:30.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:34:0) after 1m0s
[01:57:30.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:34:0)
[01:57:30.217] Timer tick, asking Buildbucket for the build status
[01:57:30.293] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:30.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:35:0) after 1m0s
[01:58:30.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:35:0)
[01:58:30.397] Timer tick, asking Buildbucket for the build status
[01:58:30.499] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:30.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:36:0) after 1m0s
[01:59:30.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:36:0)
[01:59:30.727] Timer tick, asking Buildbucket for the build status
[01:59:31.080] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:31.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:37:0) after 1m0s
[02:00:31.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:37:0)
[02:00:31.230] Timer tick, asking Buildbucket for the build status
[02:00:31.545] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:31.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:38:0) after 1m0s
[02:01:31.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:38:0)
[02:01:31.585] Timer tick, asking Buildbucket for the build status
[02:01:31.915] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:31.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:39:0) after 1m0s
[02:02:32.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:39:0)
[02:02:32.104] Timer tick, asking Buildbucket for the build status
[02:02:32.230] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:32.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:40:0) after 1m0s
[02:03:32.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:40:0)
[02:03:32.382] Timer tick, asking Buildbucket for the build status
[02:03:32.797] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:32.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:41:0) after 1m0s
[02:04:32.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:41:0)
[02:04:32.808] Timer tick, asking Buildbucket for the build status
[02:04:32.900] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:32.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:42:0) after 1m0s
[02:05:33.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:42:0)
[02:05:33.086] Timer tick, asking Buildbucket for the build status
[02:05:33.487] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:33.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:43:0) after 1m0s
[02:06:33.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:43:0)
[02:06:33.604] Timer tick, asking Buildbucket for the build status
[02:06:33.678] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:33.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:44:0) after 1m0s
[02:07:33.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:44:0)
[02:07:33.712] Timer tick, asking Buildbucket for the build status
[02:07:33.959] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:33.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:45:0) after 1m0s
[02:08:33.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:45:0)
[02:08:33.988] Timer tick, asking Buildbucket for the build status
[02:08:34.254] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:34.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:46:0) after 1m0s
[02:09:34.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:46:0)
[02:09:34.307] Timer tick, asking Buildbucket for the build status
[02:09:34.630] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:34.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:47:0) after 1m0s
[02:10:34.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:47:0)
[02:10:34.624] Timer tick, asking Buildbucket for the build status
[02:10:34.815] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:34.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:48:0) after 1m0s
[02:11:34.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:48:0)
[02:11:34.810] Timer tick, asking Buildbucket for the build status
[02:11:34.913] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:34.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:49:0) after 1m0s
[02:12:35.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:49:0)
[02:12:35.007] Timer tick, asking Buildbucket for the build status
[02:12:35.503] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:35.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:50:0) after 1m0s
[02:13:35.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:50:0)
[02:13:35.564] Timer tick, asking Buildbucket for the build status
[02:13:35.757] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:35.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:51:0) after 1m0s
[02:14:35.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:51:0)
[02:14:35.804] Timer tick, asking Buildbucket for the build status
[02:14:35.883] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:35.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:52:0) after 1m0s
[02:15:35.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:52:0)
[02:15:35.963] Timer tick, asking Buildbucket for the build status
[02:15:36.129] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:36.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:53:0) after 1m0s
[02:16:36.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:53:0)
[02:16:36.232] Timer tick, asking Buildbucket for the build status
[02:16:36.302] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:36.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:54:0) after 1m0s
[02:17:36.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:54:0)
[02:17:36.501] Timer tick, asking Buildbucket for the build status
[02:17:36.609] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:36.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:55:0) after 1m0s
[02:18:36.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:55:0)
[02:18:36.736] Timer tick, asking Buildbucket for the build status
[02:18:36.918] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:36.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:56:0) after 1m0s
[02:19:36.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:56:0)
[02:19:36.999] Timer tick, asking Buildbucket for the build status
[02:19:37.085] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:37.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:57:0) after 1m0s
[02:20:37.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:57:0)
[02:20:37.201] Timer tick, asking Buildbucket for the build status
[02:20:37.364] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:37.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:58:0) after 1m0s
[02:21:37.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:58:0)
[02:21:37.455] Timer tick, asking Buildbucket for the build status
[02:21:37.820] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:37.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:59:0) after 1m0s
[02:22:38.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:59:0)
[02:22:38.019] Timer tick, asking Buildbucket for the build status
[02:22:38.114] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:38.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:60:0) after 1m0s
[02:23:38.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:60:0)
[02:23:38.361] Timer tick, asking Buildbucket for the build status
[02:23:38.474] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:38.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:61:0) after 1m0s
[02:24:38.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:61:0)
[02:24:38.597] Timer tick, asking Buildbucket for the build status
[02:24:38.700] Build 8861522386167400992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:38.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026952448950027856:62:0) after 1m0s
[02:25:00.267] Received PubSub notification, asking Buildbucket for the build status
[02:25:00.343] Build 8861522386167400992: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:25:00.343] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607394298804578",
"created_by": "project:chromium",
"created_ts": "1607390718261826",
"failure_reason": "BUILD_FAILURE",
"id": "8861522386167400992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"45b34f613273af9df5b49a5939e8b67e4420a90f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsAriaDropeffectChanged/uia\\n\\n**interactive_ui_tests** failed because of:\\n\\n- MouseEventsTest.MouseDownOnBrowserCaption\\n\\n- AppWindowInteractiveTest.ESCLeavesFullscreenDOM\\n\\n- EventPage/NonPersistentExtensionTabsTest.TabCurrentWindow/0\\n\\n- BookmarksSnapshotTest.Test\\n\\n- ...308 more failure(s) (312 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"45b34f613273af9df5b49a5939e8b67e4420a90f\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-z6mr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16092, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"dea86294537c612e1f0d72d9b29b7ecb1a76dbd3\", \"got_dawn_revision\": \"042184128efea90552495d5054c3ae17b84cb3c3\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"45b34f613273af9df5b49a5939e8b67e4420a90f\", \"got_revision_cp\": \"refs/heads/master@{#834491}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7bcd79eccf28007fe7a60ed50251d91ea4e65674\", \"got_v8_revision\": \"1c9380eba0e26de8661d3b1c69778082b4771b2b\", \"got_v8_revision_cp\": \"refs/heads/8.9.118@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"45b34f613273af9df5b49a5939e8b67e4420a90f\", \"swarm_hashes_refs/heads/master(at){#834491}_without_patch\": {\"absl_hardening_tests\": \"0310ae8c2fd3e3e80398db859ccb1ff2175ecd63\", \"accessibility_unittests\": \"639892530696dfb6b4e1bd8a5127893d8188dc75\", \"angle_unittests\": \"9373e5653ae2605464d37d88c52246cb93fd9ef1\", \"app_shell_unittests\": \"8c09f738d3edba3e31ce5184c3a90e239d9a1875\", \"aura_unittests\": \"7ec8b0b636be5fad465476534b71f819e8f2bb93\", \"base_unittests\": \"3056b8b32daeca7d823b1fdb2c6b781c3f27b0bd\", \"base_util_unittests\": \"dc73be40393927482d0ce375b398dc7771ac5a37\", \"blink_common_unittests\": \"d7c1aca019d9847171c957f2efb8659cb1a37a58\", \"blink_fuzzer_unittests\": \"705429d7b9e55628014b0390b54811a123008955\", \"blink_heap_unittests\": \"6bdecc585ab1098c7cd0dc2fcd043f277fe75ed6\", \"blink_platform_unittests\": \"2e4c964f52b5e4bb83f056142f223d56720e4f56\", \"blink_unittests\": \"5bb18516d580ba5fcb5c8e70b911ab48ca1bc0a0\", \"boringssl_crypto_tests\": \"cb7bfc2dbeaca9f1e55912db5a87f986a7ebf55c\", \"boringssl_ssl_tests\": \"dfb1f0cf281997544bbcc5376be7fa3553bcb50b\", \"browser_switcher_bho_unittests\": \"2557b925860eaf45bcdc5f44ba18e65508d51808\", \"browser_tests\": \"d7fcc81e2db7781114e4e2e5e87e1f83fc9df0a9\", \"capture_unittests\": \"447c42b0433ac7f75d003f46f2435a5034f20b42\", \"cast_unittests\": \"cb74fc78129fee3a48c32375e518d895886ec60a\", \"cc_unittests\": \"513452c212a65da5fb8c7e0594bd3294244e3713\", \"chrome_app_unittests\": \"7c4f2a039e0b0576789d25c4b502082d15112d2a\", \"chrome_cleaner_unittests\": \"2565b9bd07b47cdf5ed62d07e455544799730dd9\", \"chrome_elf_unittests\": \"91860d58a8772ff05d4387ffc1321829f954e9c9\", \"chromedriver_unittests\": \"fb3ae5e88c22f44b8bc0f4edd19fb6963f1d0db6\", \"components_browsertests\": \"81c8f9fb1247d81452fa7dbc097e04b2c9e76f7d\", \"components_unittests\": \"6d1cdc68ebc16597315cd30ec1a001565cb6dd6f\", \"compositor_unittests\": \"084de7f8c81f5ebb83fb2dced51c38a07fa23a48\", \"content_browsertests\": \"9c33d1f7e9ccb440fbd0a8b7ed13228c0ee51b8d\", \"content_unittests\": \"aecde44e6701efb53b6509dbccfc7212cae6861e\", \"courgette_unittests\": \"7827927df7c9360d62d6bb4d6c779da8901042f0\", \"crashpad_tests\": \"b884dcd87b26b26528e16acddcc90e3d1235752a\", \"cronet_tests\": \"6216aec2cc945ca8f892664c18f035e25edee5a7\", \"cronet_unittests\": \"f99e6530106416e49e02b8d8afe5b1499f2e4279\", \"crypto_unittests\": \"3fe698216d08f39a7a08387e68ba3880bf8b802d\", \"delayloads_unittests\": \"916147cca9ec0a4da7087736337bb2bf31eeab2d\", \"device_unittests\": \"635b91beb33e2a870618f95402d8c077c545ce7f\", \"display_unittests\": \"f6f33c92d4db731078afc3f4ce589df582f13fbd\", \"elevation_service_unittests\": \"1c7c23f4f25d85a6cb96844247cff23f31a04219\", \"events_unittests\": \"6ad3c96c07bdc06a89941fb24c90b38bfbb309e6\", \"extensions_browsertests\": \"95ddd19b0f211a68cfd1bf92d4f7c5f679ea30ac\", \"extensions_unittests\": \"54319313459ccfe636222f09ebfcdc2781f9f218\", \"filesystem_service_unittests\": \"633d724d4510a45b22efd7d62972dbe563f825b6\", \"gcm_unit_tests\": \"2677d93b8049b99c69481b98c2a0d4a6e482365f\", \"gcp_unittests\": \"2bd627ba7112203edb07c405e314618d9a116f96\", \"gfx_unittests\": \"f4583add4e3f21fc1ad7d4b0628039f0e330b561\", \"gin_unittests\": \"4b73b46802c8253ee173ebdc7e0affcd4b5d677f\", \"google_apis_unittests\": \"9e7376dcc1195c4c13b7e2d8b6bacf81eda090e7\", \"gpu_unittests\": \"ec3a61b477010410996a325ade188f5aeaff394d\", \"gwp_asan_unittests\": \"944dd1100cac47044786aaaa001591e478b5acbe\", \"headless_browsertests\": \"c2d0dcaf149193b83cd6f7955dd8bd8e43b82bf8\", \"headless_unittests\": \"a9fb216437cc5f944d22c71c0ab14663bf2b0788\", \"install_static_unittests\": \"c58812b7c2870433fc598fb819bc9cbc31a2e968\", \"installer_util_unittests\": \"4b528d04b6aa2f564001ef1f6f177df11c1919a4\", \"interactive_ui_tests\": \"81508d674cd6917cf99d8110913fa9ae73d34fa7\", \"ipc_tests\": \"42d522049dc595c92c1f190aa3389175b0546cac\", \"jingle_unittests\": \"22ddd8e0335e6f4e005b4e550fc7725e5020115a\", \"latency_unittests\": \"de025d613c77527b1ba1bb83aa36c3b00328b53b\", \"libjingle_xmpp_unittests\": \"329131327df3f9bd1a4d393d447eef9e5d2323cf\", \"liburlpattern_unittests\": \"42094932f07d11d11418181e973004c487bc69c3\", \"media_blink_unittests\": \"245b5c322242d09421f5779e76bccb10f0d83441\", \"media_unittests\": \"e71e1801b330fef13450dbe0418434460a9f9d72\", \"message_center_unittests\": \"7d0847b8e7c5de6c408cbf046689588f095d890c\", \"midi_unittests\": \"27f0654c322cc3583c5a8ea1214e47f20f020da6\", \"mojo_core_unittests\": \"986c6455ff33074ac161b3702bf201d01286631a\", \"mojo_unittests\": \"a08475cc450147a1b065c58100ac1a04b6ad61af\", \"nacl_loader_unittests\": \"4d42de45631fc2877b1efbff9ebb90793cd8dfd8\", \"native_theme_unittests\": \"a45be13737026e99f045aa0c89ed656acf4c7628\", \"net_unittests\": \"2458a6d8b1b2f86a11d30796158b8c841bc2c94e\", \"notification_helper_unittests\": \"470c580cb69e12565405eba2554dbb656cfafb62\", \"pdf_unittests\": \"659ce976b23723d50308f4c44350a595d325ac47\", \"perfetto_unittests\": \"c86eca6028efa4fdc9c079d256fc7d1850eee5b1\", \"ppapi_unittests\": \"3912d7f4e0836c840d3bf5993dcf1dcb26a48892\", \"printing_unittests\": \"2de9855fc938a0b5afef8791e45e78dffafac6a6\", \"remoting_unittests\": \"cbd284a04481adac9f4e3549b3f5f9839f1de99d\", \"sbox_integration_tests\": \"be1f2e39539b550cc8fc41237994bfd794bd78df\", \"sbox_unittests\": \"24d8ae3466879ce746e5a93e87cb5e0a3f70df93\", \"sbox_validation_tests\": \"f0d0a11f4f29732235bc4581ed779a433b633e29\", \"service_manager_unittests\": \"7be0bdee5709471c52434c89c5f0d35793157043\", \"services_unittests\": \"c3052f14dd1ecec718e521fbe141aef07328d58f\", \"setup_unittests\": \"12f6dc7b5804a6fe091cf0f917573eec6bf9fa2a\", \"shell_dialogs_unittests\": \"88ca1dc48e6c8f7e7e534eb74af3515a3548e273\", \"skia_unittests\": \"ed29dbcfa89a286f7f479bc32c89dc3ffd077465\", \"snapshot_unittests\": \"97e3186bcf560b00b275da154d3ab22230abb0b4\", \"sql_unittests\": \"fbadf6450222db1e77c74a99748e55a68bf75fb6\", \"storage_unittests\": \"a598b13c6522be155fd490e7db99ad4d26e4dadd\", \"sync_integration_tests\": \"d72c0ad43831280c25a32da00e71b9139ee1af0c\", \"traffic_annotation_auditor_unittests\": \"9d4fc1b4d9c41f614232a2c663b1e3fa9f792391\", \"ui_base_unittests\": \"6254a8c6f69fa48ed6447e52a2e3d404fcc2dc7a\", \"ui_touch_selection_unittests\": \"cb484a184a5549393aedebc96e753cc2254fc2f3\", \"unit_tests\": \"391c997888736be82069ccf66eaaecbcb5847b4f\", \"updater_tests\": \"9740d844863471b960c5c80a6033c27ed3da58a8\", \"url_unittests\": \"e42658813bf77e30abeb09c735406638543fbe40\", \"views_unittests\": \"7298963a202a0e1bbc47a0e561b9a3cec42f13bb\", \"viz_unittests\": \"885dec5a81b53ecb4a3c15e3d42411a91c3f12a6\", \"vr_common_unittests\": \"51c4b514486e3375093819b3b09dd6220d35ca7a\", \"vr_pixeltests\": \"2b5b9cd0ff06336eb1cf8abe328b6d74774e5451\", \"weblayer_browsertests\": \"87f59e1b8d370e77ba7368054eea3e44c5a9a67c\", \"weblayer_unittests\": \"b1d44b08282010400afadba06f553f629e8c4f87\", \"wm_unittests\": \"089e3dfe392aaf80ceaa1390a5b01b6799371dfc\", \"wtf_unittests\": \"2a7143c782a8e3f859fdd40498139699e36192ad\", \"zlib_unittests\": \"06648efee5e781f41d6cf6c446c18ea9f449b383\", \"zucchini_unittests\": \"4b7973c0a494faab893365f8a6f3849fd3efcbdd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-z6mr\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/DumpAccessibilityEventsTest.AccessibilityEventsAriaDropeffectChanged/uia\\n\\n**interactive_ui_tests** failed because of:\\n\\n- MouseEventsTest.MouseDownOnBrowserCaption\\n\\n- AppWindowInteractiveTest.ESCLeavesFullscreenDOM\\n\\n- EventPage/NonPersistentExtensionTabsTest.TabCurrentWindow/0\\n\\n- BookmarksSnapshotTest.Test\\n\\n- ...308 more failure(s) (312 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607390721401177",
"status": "COMPLETED",
"status_changed_ts": "1607390721877163",
"tags": [
"build_address:luci.chromium.ci/win-asan/16092",
"builder:win-asan",
"buildset:commit/git/45b34f613273af9df5b49a5939e8b67e4420a90f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b34f613273af9df5b49a5939e8b67e4420a90f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026952448950027856",
"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/8861522386167400992/+/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:5058c5746172d210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607394299965965",
"url": "https://ci.chromium.org/b/8861522386167400992",
"utcnow_ts": "1607394300335843"
}
}
[02:25:00.343] Invocation finished in 59m44.119858794s with status FAILED