[16:54:36.123] New invocation is queued and will start shortly
[16:54:37.256] Starting the invocation (attempt 1)
[16:54:37.298] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:54:37.301] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:54:37.301] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025716219912187392",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd212973136551f64881548012fe0fb2b122798c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025716219912187392",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/dd212973136551f64881548012fe0fb2b122798c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd212973136551f64881548012fe0fb2b122798c",
"gitiles_ref:refs/heads/master"
]
}
[16:54:38.324] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608569677397355",
"id": "8860286157911513264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd212973136551f64881548012fe0fb2b122798c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608569678153897",
"tags": [
"build_address:luci.chromium.ci/win-asan/16366",
"builder:win-asan",
"buildset:commit/git/dd212973136551f64881548012fe0fb2b122798c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd212973136551f64881548012fe0fb2b122798c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025716219912187392",
"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/8860286157911513264/+/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": "1608569678154055",
"url": "https://ci.chromium.org/b/8860286157911513264",
"utcnow_ts": "1608569678311503"
}
}
[16:54:38.324] Task URL: https://ci.chromium.org/b/8860286157911513264
[16:54:38.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:2:0) after 1m0s
[16:54:41.386] Received PubSub notification, asking Buildbucket for the build status
[16:54:41.450] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:38.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:2:0)
[16:55:38.382] Timer tick, asking Buildbucket for the build status
[16:55:38.464] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:38.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:4:0) after 1m0s
[16:56:38.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:4:0)
[16:56:38.588] Timer tick, asking Buildbucket for the build status
[16:56:38.683] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:5:0) after 1m0s
[16:57:38.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:5:0)
[16:57:38.788] Timer tick, asking Buildbucket for the build status
[16:57:38.893] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:38.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:6:0) after 1m0s
[16:58:39.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:6:0)
[16:58:39.505] Timer tick, asking Buildbucket for the build status
[16:58:39.692] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:39.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:7:0) after 1m0s
[16:59:40.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:7:0)
[16:59:40.478] Timer tick, asking Buildbucket for the build status
[16:59:40.871] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:40.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:8:0) after 1m0s
[17:00:40.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:8:0)
[17:00:40.923] Timer tick, asking Buildbucket for the build status
[17:00:40.990] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:40.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:9:0) after 1m0s
[17:01:41.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:9:0)
[17:01:41.414] Timer tick, asking Buildbucket for the build status
[17:01:41.574] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:41.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:10:0) after 1m0s
[17:02:42.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:10:0)
[17:02:42.067] Timer tick, asking Buildbucket for the build status
[17:02:42.135] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:42.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:11:0) after 1m0s
[17:03:42.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:11:0)
[17:03:42.254] Timer tick, asking Buildbucket for the build status
[17:03:42.405] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:42.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:12:0) after 1m0s
[17:04:42.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:12:0)
[17:04:42.534] Timer tick, asking Buildbucket for the build status
[17:04:42.889] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:42.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:13:0) after 1m0s
[17:05:42.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:13:0)
[17:05:42.963] Timer tick, asking Buildbucket for the build status
[17:05:43.088] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:43.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:14:0) after 1m0s
[17:06:43.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:14:0)
[17:06:43.159] Timer tick, asking Buildbucket for the build status
[17:06:43.299] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:43.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:15:0) after 1m0s
[17:07:43.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:15:0)
[17:07:43.321] Timer tick, asking Buildbucket for the build status
[17:07:43.377] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:43.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:16:0) after 1m0s
[17:08:43.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:16:0)
[17:08:43.453] Timer tick, asking Buildbucket for the build status
[17:08:43.590] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:43.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:17:0) after 1m0s
[17:09:43.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:17:0)
[17:09:43.575] Timer tick, asking Buildbucket for the build status
[17:09:43.682] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:43.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:18:0) after 1m0s
[17:10:43.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:18:0)
[17:10:43.700] Timer tick, asking Buildbucket for the build status
[17:10:43.815] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:43.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:19:0) after 1m0s
[17:11:43.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:19:0)
[17:11:43.873] Timer tick, asking Buildbucket for the build status
[17:11:44.009] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:44.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:20:0) after 1m0s
[17:12:44.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:20:0)
[17:12:44.026] Timer tick, asking Buildbucket for the build status
[17:12:44.154] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:21:0) after 1m0s
[17:13:44.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:21:0)
[17:13:44.168] Timer tick, asking Buildbucket for the build status
[17:13:44.272] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:44.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:22:0) after 1m0s
[17:14:46.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:22:0)
[17:14:46.850] Timer tick, asking Buildbucket for the build status
[17:14:47.289] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:47.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:23:0) after 1m0s
[17:15:47.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:23:0)
[17:15:47.469] Timer tick, asking Buildbucket for the build status
[17:15:47.584] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:47.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:24:0) after 1m0s
[17:16:47.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:24:0)
[17:16:47.655] Timer tick, asking Buildbucket for the build status
[17:16:47.783] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:47.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:25:0) after 1m0s
[17:17:47.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:25:0)
[17:17:47.942] Timer tick, asking Buildbucket for the build status
[17:17:48.055] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:48.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:26:0) after 1m0s
[17:18:48.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:26:0)
[17:18:48.214] Timer tick, asking Buildbucket for the build status
[17:18:48.669] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:48.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:27:0) after 1m0s
[17:19:48.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:27:0)
[17:19:48.765] Timer tick, asking Buildbucket for the build status
[17:19:48.921] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:28:0) after 1m0s
[17:20:49.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:28:0)
[17:20:49.027] Timer tick, asking Buildbucket for the build status
[17:20:49.157] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:49.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:29:0) after 1m0s
[17:21:49.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:29:0)
[17:21:49.233] Timer tick, asking Buildbucket for the build status
[17:21:49.618] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:49.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:30:0) after 1m0s
[17:22:49.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:30:0)
[17:22:49.719] Timer tick, asking Buildbucket for the build status
[17:22:49.905] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:49.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:31:0) after 1m0s
[17:23:50.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:31:0)
[17:23:50.032] Timer tick, asking Buildbucket for the build status
[17:23:50.112] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:50.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:32:0) after 1m0s
[17:24:50.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:32:0)
[17:24:50.154] Timer tick, asking Buildbucket for the build status
[17:24:50.301] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:50.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:33:0) after 1m0s
[17:25:50.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:33:0)
[17:25:50.360] Timer tick, asking Buildbucket for the build status
[17:25:50.483] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:50.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:34:0) after 1m0s
[17:26:50.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:34:0)
[17:26:50.506] Timer tick, asking Buildbucket for the build status
[17:26:50.642] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:50.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:35:0) after 1m0s
[17:27:50.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:35:0)
[17:27:50.701] Timer tick, asking Buildbucket for the build status
[17:27:51.107] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:51.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:36:0) after 1m0s
[17:28:51.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:36:0)
[17:28:51.135] Timer tick, asking Buildbucket for the build status
[17:28:51.272] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:51.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:37:0) after 1m0s
[17:29:51.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:37:0)
[17:29:51.298] Timer tick, asking Buildbucket for the build status
[17:29:51.433] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:51.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:38:0) after 1m0s
[17:30:51.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:38:0)
[17:30:51.451] Timer tick, asking Buildbucket for the build status
[17:30:51.621] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:51.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:39:0) after 1m0s
[17:31:51.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:39:0)
[17:31:51.683] Timer tick, asking Buildbucket for the build status
[17:31:51.776] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:51.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:40:0) after 1m0s
[17:32:51.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:40:0)
[17:32:51.816] Timer tick, asking Buildbucket for the build status
[17:32:51.944] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:51.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:41:0) after 1m0s
[17:33:52.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:41:0)
[17:33:52.353] Timer tick, asking Buildbucket for the build status
[17:33:52.893] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:52.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:42:0) after 1m0s
[17:34:53.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:42:0)
[17:34:53.176] Timer tick, asking Buildbucket for the build status
[17:34:53.825] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:53.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:43:0) after 1m0s
[17:35:54.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:43:0)
[17:35:54.345] Timer tick, asking Buildbucket for the build status
[17:35:54.454] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:54.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:44:0) after 1m0s
[17:36:54.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:44:0)
[17:36:54.570] Timer tick, asking Buildbucket for the build status
[17:36:55.204] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:55.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:45:0) after 1m0s
[17:37:56.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:45:0)
[17:37:56.147] Timer tick, asking Buildbucket for the build status
[17:37:56.457] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:46:0) after 1m0s
[17:38:56.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:46:0)
[17:38:56.501] Timer tick, asking Buildbucket for the build status
[17:38:56.673] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:56.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:47:0) after 1m0s
[17:39:56.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:47:0)
[17:39:56.804] Timer tick, asking Buildbucket for the build status
[17:39:57.102] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:57.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:48:0) after 1m0s
[17:40:57.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:48:0)
[17:40:57.180] Timer tick, asking Buildbucket for the build status
[17:40:57.294] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:57.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:49:0) after 1m0s
[17:41:57.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:49:0)
[17:41:57.491] Timer tick, asking Buildbucket for the build status
[17:41:57.709] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:57.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:50:0) after 1m0s
[17:42:57.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:50:0)
[17:42:57.871] Timer tick, asking Buildbucket for the build status
[17:42:58.040] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:58.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:51:0) after 1m0s
[17:43:58.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:51:0)
[17:43:58.107] Timer tick, asking Buildbucket for the build status
[17:43:58.176] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:58.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:52:0) after 1m0s
[17:44:58.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:52:0)
[17:44:58.269] Timer tick, asking Buildbucket for the build status
[17:44:58.423] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:58.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:53:0) after 1m0s
[17:45:58.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:53:0)
[17:45:58.492] Timer tick, asking Buildbucket for the build status
[17:45:58.614] Build 8860286157911513264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:58.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025716219912187392:54:0) after 1m0s
[17:46:04.549] Received PubSub notification, asking Buildbucket for the build status
[17:46:04.921] Build 8860286157911513264: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:46:04.921] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608572763570597",
"created_by": "project:chromium",
"created_ts": "1608569677397355",
"failure_reason": "BUILD_FAILURE",
"id": "8860286157911513264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd212973136551f64881548012fe0fb2b122798c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ImageAnnotationBrowserTest.TutorMessageOnlyOnFirstImage\"}, \"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\": \"dd212973136551f64881548012fe0fb2b122798c\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16366, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4ebcee3eb02ffdd586e20965b9c4bb609876db36\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"dd212973136551f64881548012fe0fb2b122798c\", \"got_revision_cp\": \"refs/heads/master@{#838661}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"b96d7e2a4be85264b9cd7ba26d91f7fb98baa9a5\", \"got_v8_revision_cp\": \"refs/heads/8.9.179@{#1}\", \"got_webrtc_revision\": \"1e98f95391647887d0db25dd5d9dcfba7f87ebd7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32866}\", \"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\": \"dd212973136551f64881548012fe0fb2b122798c\", \"swarm_hashes_refs/heads/master(at){#838661}_without_patch\": {\"absl_hardening_tests\": \"82850a7f813fd9a083ed7982c7443a5c5aaee4ca\", \"accessibility_unittests\": \"4db2430e8dca180de48cd733666e2c7cb7daa5d2\", \"angle_unittests\": \"1d65f451ec25b0ea51f44412a7b7b1e790c5ed2e\", \"app_shell_unittests\": \"839ae3df2a77a7e96f2b55664276eff76f3ebf80\", \"aura_unittests\": \"09e0a757a7292df7424967e85002b58349fd1f5f\", \"base_unittests\": \"fc06ef66046e8924a972ca2fa6d4a78aa00f734e\", \"base_util_unittests\": \"97ef970a49bb03884aa200b47428c94f744a4334\", \"blink_common_unittests\": \"bd5803aa25d1b68dab17b66a99d1824aa8c455a7\", \"blink_fuzzer_unittests\": \"d2797cbd418a6e02220ce8e58362d2ce4f01212d\", \"blink_heap_unittests\": \"7b78afaf0571b772a21fcdfb1dd5bbc00cf4d760\", \"blink_platform_unittests\": \"aa52b6af9d3092367b8872c3cd88055fe2558e8a\", \"blink_unittests\": \"3aee9b7ba6316517397077d8eb5ce828fa9d007b\", \"boringssl_crypto_tests\": \"b5f43e4915c555f0f0a79a2e7a89dff7d4ae57c8\", \"boringssl_ssl_tests\": \"4b332d518ca3d3ce5532690fbc210ee41572403f\", \"browser_switcher_bho_unittests\": \"31cd18d8b1eec89e415b331a9d408f1e0dc312e8\", \"browser_tests\": \"0c2385587b0cfaafa8bd4559a9a301e074006a42\", \"capture_unittests\": \"a86685d8f8b03f158d17e51d6e2979fd73732972\", \"cast_unittests\": \"1907b1f41e6c455d580275bf159676690f7f8e49\", \"cc_unittests\": \"8707ed434e1380f0be99fbef5831ef17d3dc0c8c\", \"chrome_app_unittests\": \"037413f3e1250192e50ebec6e3f248a96a39274f\", \"chrome_cleaner_unittests\": \"7f2e271f440248248808e7194aca6b88bcfa6e64\", \"chrome_elf_unittests\": \"f899becfca35f8e3f1817345b413ed84d4d9857c\", \"chromedriver_unittests\": \"ac8cd41203105065a861bfe8337616055edd0053\", \"components_browsertests\": \"2726a293b9c55de868ff59817bcb810b1b694aa1\", \"components_unittests\": \"575a5146977bcbea6f3bc5a8dafe88fc02b5d867\", \"compositor_unittests\": \"ba16313de10d707aa0a83ccc7085174c5506339a\", \"content_browsertests\": \"53ae75e28b6be4e7b919f80b5cfafdfc955c50c5\", \"content_unittests\": \"1de8a141d08c3a14dba2c2beaf011d9cb7d194ee\", \"courgette_unittests\": \"853d8778587fdee67067bccccaaac69f4d6451f0\", \"crashpad_tests\": \"7a0f8e6f613f70c00737a1e0e0abbe0b1eb87f81\", \"cronet_tests\": \"92c7136bf72828149ded99a1077bde5fbb55ea34\", \"cronet_unittests\": \"7264acaec5f29ec22921784c1c71ce771d209f0d\", \"crypto_unittests\": \"bf030e9230c3f3b4fd1feaf333da15001844c088\", \"delayloads_unittests\": \"c031e1bc0c1d70addff5cc7a7732142df3ae698e\", \"device_unittests\": \"22cda0dae37688e39a083592daecbf7504378d02\", \"display_unittests\": \"de42da63b27a5a27e2b56546d67dbee1948078af\", \"elevation_service_unittests\": \"967cafe52bfcdc250bbcfa1d61ececb109206324\", \"events_unittests\": \"f57acd6101efd1b854591f5608205c2555ee45e5\", \"extensions_browsertests\": \"ac53d0d081e367e89ee091aeb1acb6e5e757f6b0\", \"extensions_unittests\": \"d806f8a4253028807e167ce1e8680bdf535da91e\", \"filesystem_service_unittests\": \"25e6aa51f80d794f42ea2f62729ccbfaa938d994\", \"gcm_unit_tests\": \"af9eb2cd669afb34810fbcfe99c008b547c44219\", \"gcp_unittests\": \"7c6680319725679881a9aa2736190c9c43fd0878\", \"gfx_unittests\": \"c9baa6178618c84c0d8a65cb3f527fd57b23b162\", \"gin_unittests\": \"568af2b613a765cc32f48ba8fbaaec6f35899e9f\", \"google_apis_unittests\": \"ad1161a84bcbf11a67d5b30faa371f1c6078b279\", \"gpu_unittests\": \"1ffbbdb8fb1238f0d6efaff66d90e5f800466b4b\", \"gwp_asan_unittests\": \"6b102e4d9335c1f6241800a7558034dcf5d17124\", \"headless_browsertests\": \"004092c162f139a2370eb4a31ccb7fe396dc16fa\", \"headless_unittests\": \"fb644c03b2455cc57dee0149d6e415e4bc511754\", \"install_static_unittests\": \"1279ac7f9c508c934448f8e2eaa424bdaf8bedf9\", \"installer_util_unittests\": \"087c6908a21b4083ed31a906a05009280b974475\", \"interactive_ui_tests\": \"9f3e265a90cf817bd9c9d540a180755f4997e538\", \"ipc_tests\": \"53adbb019a421c00d3a485e6af34308ce5e7ba7a\", \"jingle_unittests\": \"0bfb7d7647f51441533db0b2171f2dd613058886\", \"latency_unittests\": \"41bec815811117a82519eb9e4c3cfcaa2381e3db\", \"libjingle_xmpp_unittests\": \"d5d8bdf34e77507faaa1d41aea3c03f68e01ee2a\", \"liburlpattern_unittests\": \"d3b46ec2546283c045454e49a2aa112f8950e297\", \"media_blink_unittests\": \"8b985a07ddf83573cd7d2bb372dffd34a5d54329\", \"media_unittests\": \"027be3c80566681fc25a0071bf101411bcad0fd2\", \"message_center_unittests\": \"ec23511d15fcc0d0c0a3bf06c6b0be4d74505920\", \"midi_unittests\": \"f5530a0392942a800ff1db9dc955bd377ded85cd\", \"mojo_core_unittests\": \"d5191911cbf3f3b3f97c25abaa9be4e9a473ea82\", \"mojo_unittests\": \"8c3d474e27e05afc689196cdba59f3cc36b624c2\", \"nacl_loader_unittests\": \"19316933287951b3c43a5cfc1599da19b7c84101\", \"native_theme_unittests\": \"883bfe11c80ae9fef1736efe984976584959ed66\", \"net_unittests\": \"7c649bc24fee8ac5d69e50ee24186ad364d08b98\", \"notification_helper_unittests\": \"fc6555e9492c5cf391c07f29211ff3dcc9fb3ea2\", \"pdf_unittests\": \"8e561e95406f613285fcf6d6da93b081db1ab746\", \"perfetto_unittests\": \"c8f84016658b36689c6c78d3e23e4a33f11499a6\", \"ppapi_unittests\": \"75d75e391757101798d9bdb04aa76612ff33342d\", \"printing_unittests\": \"cf0a144b903a8ea9f07949ab145d6ae2411870a1\", \"remoting_unittests\": \"3db99eed2a0936baed9d2e0b3aa143b4328556a7\", \"sbox_integration_tests\": \"c570856925d59b43869ae38932ee32322ebee7fb\", \"sbox_unittests\": \"9e81a0f6533bb11d314f046736673f8c2c70fafc\", \"sbox_validation_tests\": \"c4c0c79337def2aeb065af89092006b488733831\", \"service_manager_unittests\": \"bd4e77bc438903f82ac4895a785e1045d20fc343\", \"services_unittests\": \"5d8dfd8576cd84970a5dfa0bb2961e94e598cc15\", \"setup_unittests\": \"d58f2a3b6976e067d3417e90c891a55ed1fec67e\", \"shell_dialogs_unittests\": \"473952bae799a4e8fd72353414d068c33c058edb\", \"skia_unittests\": \"db222b8d309b5acfdbcd5ed5220c97ffcfe2353b\", \"snapshot_unittests\": \"4c48f8a12cfbb91154941ec2d67102a9744b9f32\", \"sql_unittests\": \"02c60f17060abab4a6b2a8ead6bf7ddec13d842a\", \"storage_unittests\": \"cd3601f87a8b9d3b18fea37b6a855ca1ab6382e7\", \"sync_integration_tests\": \"d8d0f2e284bd879c957a217778acef3bfcb13739\", \"traffic_annotation_auditor_unittests\": \"719ba234b50acbaef038e98eaa84b4f21f895a68\", \"ui_base_unittests\": \"62a6b44ae4b870cb0ca7074551ca61d5662ee384\", \"ui_touch_selection_unittests\": \"76e6080adcb5e9cbc50494cd7b46d63e14127076\", \"unit_tests\": \"61f0ed80fcaecc2af970e17ff28123e941b46994\", \"updater_tests\": \"260067afb79619405ebd614a3ce4aea0108069f0\", \"url_unittests\": \"99ca02adfc8f11d972c6f0e1b530c55b41f6a633\", \"views_unittests\": \"0b202fb4459c6da53dc3e3c9457b48fe380ddc74\", \"viz_unittests\": \"6523d8f21245c763e31f7d94e2c99c1e47e2e376\", \"vr_common_unittests\": \"32e88193e1a8707f106b2497d0dea12f7a1cfc67\", \"vr_pixeltests\": \"6381b7bdd44924369278b59160fed842c97ddfbf\", \"weblayer_browsertests\": \"2f7a2d5b90d9058d0ab27c8e2401d7ac6fed1b4d\", \"weblayer_unittests\": \"d085c33fb674347849abce1ed1044aa3c2f1ea1d\", \"wm_unittests\": \"ad70733b40f882cbb734c9c11678f3450c86d226\", \"wtf_unittests\": \"fa7ab899dd3b1ba8817c12251ef45bc989fad160\", \"zlib_unittests\": \"0ff49215505f0060c1f23b43c974c5837a77553a\", \"zucchini_unittests\": \"5d8eb9ac0295c1d97fbad4dd7a79661e742da33c\"}}, \"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-6-gpyc\"], \"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\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ImageAnnotationBrowserTest.TutorMessageOnlyOnFirstImage\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608569680844527",
"status": "COMPLETED",
"status_changed_ts": "1608569681157950",
"tags": [
"build_address:luci.chromium.ci/win-asan/16366",
"builder:win-asan",
"buildset:commit/git/dd212973136551f64881548012fe0fb2b122798c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd212973136551f64881548012fe0fb2b122798c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025716219912187392",
"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/8860286157911513264/+/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:509f0af23545cf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608572764270444",
"url": "https://ci.chromium.org/b/8860286157911513264",
"utcnow_ts": "1608572764855864"
}
}
[17:46:04.921] Invocation finished in 51m28.815444888s with status FAILED