[04:31:52.131] New invocation is queued and will start shortly
[04:31:53.263] Starting the invocation (attempt 1)
[04:31:53.304] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:31:53.304] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:31:53.305] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022501457853991552",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022501457853991552",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master"
]
}
[04:31:54.024] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611635513383861",
"id": "8857071395876699344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611635513794512",
"tags": [
"build_address:luci.chromium.ci/win-asan/17014",
"builder:win-asan",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022501457853991552",
"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/8857071395876699344/+/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": "1611635513794624",
"url": "https://ci.chromium.org/b/8857071395876699344",
"utcnow_ts": "1611635514016965"
}
}
[04:31:54.024] Task URL: https://ci.chromium.org/b/8857071395876699344
[04:31:54.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:2:0) after 1m0s
[04:32:54.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:2:0)
[04:32:54.037] Timer tick, asking Buildbucket for the build status
[04:32:54.175] Build 8857071395876699344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:54.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:3:0) after 1m0s
[04:33:54.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:3:0)
[04:33:54.171] Timer tick, asking Buildbucket for the build status
[04:33:54.249] Build 8857071395876699344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:54.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:4:0) after 1m0s
[04:34:54.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:4:0)
[04:34:54.319] Timer tick, asking Buildbucket for the build status
[04:34:54.606] Build 8857071395876699344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:54.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:5:0) after 1m0s
[04:35:54.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:5:0)
[04:35:54.655] Timer tick, asking Buildbucket for the build status
[04:35:54.784] Build 8857071395876699344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:6:0) after 1m0s
[04:36:42.588] Received PubSub notification, asking Buildbucket for the build status
[04:36:42.661] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:54.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:6:0)
[04:36:54.800] Timer tick, asking Buildbucket for the build status
[04:36:54.878] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:54.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:8:0) after 1m0s
[04:37:54.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:8:0)
[04:37:54.921] Timer tick, asking Buildbucket for the build status
[04:37:55.248] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:55.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:9:0) after 1m0s
[04:38:55.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:9:0)
[04:38:55.271] Timer tick, asking Buildbucket for the build status
[04:38:55.463] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:55.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:10:0) after 1m0s
[04:39:55.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:10:0)
[04:39:55.529] Timer tick, asking Buildbucket for the build status
[04:39:55.600] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:11:0) after 1m0s
[04:40:55.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:11:0)
[04:40:55.719] Timer tick, asking Buildbucket for the build status
[04:40:55.987] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:55.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:12:0) after 1m0s
[04:41:56.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:12:0)
[04:41:56.103] Timer tick, asking Buildbucket for the build status
[04:41:56.234] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:56.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:13:0) after 1m0s
[04:42:56.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:13:0)
[04:42:56.301] Timer tick, asking Buildbucket for the build status
[04:42:56.400] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:56.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:14:0) after 1m0s
[04:43:56.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:14:0)
[04:43:56.417] Timer tick, asking Buildbucket for the build status
[04:43:56.498] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:56.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:15:0) after 1m0s
[04:44:56.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:15:0)
[04:44:56.583] Timer tick, asking Buildbucket for the build status
[04:44:56.662] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:56.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:16:0) after 1m0s
[04:45:56.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:16:0)
[04:45:56.727] Timer tick, asking Buildbucket for the build status
[04:45:56.831] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:56.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:17:0) after 1m0s
[04:46:56.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:17:0)
[04:46:56.884] Timer tick, asking Buildbucket for the build status
[04:46:57.023] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:57.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:18:0) after 1m0s
[04:47:57.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:18:0)
[04:47:57.063] Timer tick, asking Buildbucket for the build status
[04:47:57.313] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:57.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:19:0) after 1m0s
[04:48:57.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:19:0)
[04:48:57.413] Timer tick, asking Buildbucket for the build status
[04:48:57.528] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:57.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:20:0) after 1m0s
[04:49:57.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:20:0)
[04:49:57.599] Timer tick, asking Buildbucket for the build status
[04:49:57.725] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:57.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:21:0) after 1m0s
[04:50:57.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:21:0)
[04:50:57.746] Timer tick, asking Buildbucket for the build status
[04:50:57.871] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:57.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:22:0) after 1m0s
[04:51:57.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:22:0)
[04:51:57.892] Timer tick, asking Buildbucket for the build status
[04:51:58.191] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:58.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:23:0) after 1m0s
[04:52:58.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:23:0)
[04:52:58.273] Timer tick, asking Buildbucket for the build status
[04:52:58.355] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:58.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:24:0) after 1m0s
[04:53:58.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:24:0)
[04:53:58.334] Timer tick, asking Buildbucket for the build status
[04:53:58.387] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:58.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:25:0) after 1m0s
[04:54:58.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:25:0)
[04:54:58.495] Timer tick, asking Buildbucket for the build status
[04:54:58.577] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:58.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:26:0) after 1m0s
[04:55:58.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:26:0)
[04:55:58.591] Timer tick, asking Buildbucket for the build status
[04:55:58.863] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:58.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:27:0) after 1m0s
[04:56:59.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:27:0)
[04:56:59.026] Timer tick, asking Buildbucket for the build status
[04:56:59.146] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:59.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:28:0) after 1m0s
[04:57:59.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:28:0)
[04:57:59.220] Timer tick, asking Buildbucket for the build status
[04:57:59.302] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:59.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:29:0) after 1m0s
[04:58:59.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:29:0)
[04:58:59.307] Timer tick, asking Buildbucket for the build status
[04:58:59.369] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:59.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:30:0) after 1m0s
[04:59:59.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:30:0)
[04:59:59.405] Timer tick, asking Buildbucket for the build status
[04:59:59.787] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:59.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:31:0) after 1m0s
[05:00:59.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:31:0)
[05:00:59.886] Timer tick, asking Buildbucket for the build status
[05:00:59.998] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:59.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:32:0) after 1m0s
[05:02:00.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:32:0)
[05:02:00.085] Timer tick, asking Buildbucket for the build status
[05:02:00.286] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:00.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:33:0) after 1m0s
[05:03:00.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:33:0)
[05:03:00.293] Timer tick, asking Buildbucket for the build status
[05:03:00.563] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:00.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:34:0) after 1m0s
[05:04:01.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:34:0)
[05:04:01.132] Timer tick, asking Buildbucket for the build status
[05:04:01.217] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:01.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:35:0) after 1m0s
[05:05:01.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:35:0)
[05:05:01.330] Timer tick, asking Buildbucket for the build status
[05:05:01.991] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:01.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:36:0) after 1m0s
[05:06:02.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:36:0)
[05:06:02.311] Timer tick, asking Buildbucket for the build status
[05:06:02.414] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:02.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:37:0) after 1m0s
[05:07:02.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:37:0)
[05:07:02.551] Timer tick, asking Buildbucket for the build status
[05:07:02.668] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:02.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:38:0) after 1m0s
[05:08:02.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:38:0)
[05:08:02.861] Timer tick, asking Buildbucket for the build status
[05:08:03.077] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:03.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:39:0) after 1m0s
[05:09:03.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:39:0)
[05:09:03.185] Timer tick, asking Buildbucket for the build status
[05:09:03.452] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:03.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:40:0) after 1m0s
[05:10:03.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:40:0)
[05:10:03.531] Timer tick, asking Buildbucket for the build status
[05:10:03.590] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:03.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:41:0) after 1m0s
[05:11:03.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:41:0)
[05:11:03.673] Timer tick, asking Buildbucket for the build status
[05:11:03.751] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:03.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:42:0) after 1m0s
[05:12:03.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:42:0)
[05:12:03.802] Timer tick, asking Buildbucket for the build status
[05:12:04.119] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:04.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:43:0) after 1m0s
[05:13:04.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:43:0)
[05:13:04.126] Timer tick, asking Buildbucket for the build status
[05:13:04.218] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:04.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:44:0) after 1m0s
[05:14:04.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:44:0)
[05:14:04.325] Timer tick, asking Buildbucket for the build status
[05:14:04.392] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:04.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:45:0) after 1m0s
[05:15:04.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:45:0)
[05:15:04.428] Timer tick, asking Buildbucket for the build status
[05:15:04.510] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:04.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:46:0) after 1m0s
[05:16:04.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:46:0)
[05:16:04.594] Timer tick, asking Buildbucket for the build status
[05:16:04.659] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:04.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:47:0) after 1m0s
[05:17:04.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:47:0)
[05:17:04.712] Timer tick, asking Buildbucket for the build status
[05:17:04.832] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:04.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:48:0) after 1m0s
[05:18:04.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:48:0)
[05:18:04.815] Timer tick, asking Buildbucket for the build status
[05:18:04.925] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:04.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:49:0) after 1m0s
[05:19:04.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:49:0)
[05:19:04.973] Timer tick, asking Buildbucket for the build status
[05:19:05.095] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:05.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:50:0) after 1m0s
[05:20:05.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:50:0)
[05:20:05.179] Timer tick, asking Buildbucket for the build status
[05:20:05.293] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:05.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:51:0) after 1m0s
[05:21:05.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:51:0)
[05:21:05.408] Timer tick, asking Buildbucket for the build status
[05:21:05.498] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:05.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:52:0) after 1m0s
[05:22:05.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:52:0)
[05:22:05.592] Timer tick, asking Buildbucket for the build status
[05:22:05.701] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:05.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:53:0) after 1m0s
[05:23:05.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:53:0)
[05:23:05.779] Timer tick, asking Buildbucket for the build status
[05:23:05.865] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:05.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:54:0) after 1m0s
[05:24:05.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:54:0)
[05:24:05.885] Timer tick, asking Buildbucket for the build status
[05:24:06.250] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:55:0) after 1m0s
[05:25:06.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:55:0)
[05:25:06.302] Timer tick, asking Buildbucket for the build status
[05:25:06.383] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:06.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:56:0) after 1m0s
[05:26:06.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:56:0)
[05:26:06.477] Timer tick, asking Buildbucket for the build status
[05:26:06.589] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:06.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:57:0) after 1m0s
[05:27:06.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:57:0)
[05:27:06.654] Timer tick, asking Buildbucket for the build status
[05:27:06.789] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:06.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:58:0) after 1m0s
[05:28:06.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:58:0)
[05:28:06.868] Timer tick, asking Buildbucket for the build status
[05:28:07.771] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:07.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:59:0) after 1m0s
[05:29:07.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:59:0)
[05:29:07.827] Timer tick, asking Buildbucket for the build status
[05:29:07.888] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:07.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:60:0) after 1m0s
[05:30:07.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:60:0)
[05:30:07.925] Timer tick, asking Buildbucket for the build status
[05:30:08.132] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:08.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:61:0) after 1m0s
[05:31:08.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:61:0)
[05:31:08.164] Timer tick, asking Buildbucket for the build status
[05:31:08.509] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:08.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:62:0) after 1m0s
[05:32:08.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:62:0)
[05:32:08.592] Timer tick, asking Buildbucket for the build status
[05:32:08.825] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:08.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:63:0) after 1m0s
[05:33:08.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:63:0)
[05:33:08.867] Timer tick, asking Buildbucket for the build status
[05:33:09.049] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:09.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:64:0) after 1m0s
[05:34:09.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:64:0)
[05:34:09.116] Timer tick, asking Buildbucket for the build status
[05:34:09.952] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:09.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:65:0) after 1m0s
[05:35:09.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:65:0)
[05:35:09.964] Timer tick, asking Buildbucket for the build status
[05:35:11.576] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:11.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:66:0) after 1m0s
[05:36:11.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:66:0)
[05:36:11.598] Timer tick, asking Buildbucket for the build status
[05:36:12.066] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:12.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:67:0) after 1m0s
[05:37:12.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:67:0)
[05:37:12.166] Timer tick, asking Buildbucket for the build status
[05:37:12.274] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:12.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:68:0) after 1m0s
[05:38:12.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:68:0)
[05:38:12.376] Timer tick, asking Buildbucket for the build status
[05:38:12.515] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:12.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:69:0) after 1m0s
[05:39:12.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:69:0)
[05:39:12.637] Timer tick, asking Buildbucket for the build status
[05:39:13.108] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:13.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:70:0) after 1m0s
[05:40:13.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:70:0)
[05:40:13.161] Timer tick, asking Buildbucket for the build status
[05:40:13.372] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:13.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:71:0) after 1m0s
[05:41:13.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:71:0)
[05:41:13.403] Timer tick, asking Buildbucket for the build status
[05:41:14.255] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:14.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:72:0) after 1m0s
[05:42:14.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:72:0)
[05:42:14.353] Timer tick, asking Buildbucket for the build status
[05:42:15.987] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:15.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:73:0) after 1m0s
[05:43:16.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:73:0)
[05:43:16.228] Timer tick, asking Buildbucket for the build status
[05:43:16.526] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:16.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:74:0) after 1m0s
[05:44:16.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:74:0)
[05:44:16.562] Timer tick, asking Buildbucket for the build status
[05:44:16.859] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:16.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:75:0) after 1m0s
[05:45:17.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:75:0)
[05:45:17.301] Timer tick, asking Buildbucket for the build status
[05:45:17.392] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:17.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:76:0) after 1m0s
[05:46:17.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:76:0)
[05:46:17.580] Timer tick, asking Buildbucket for the build status
[05:46:17.700] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:17.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:77:0) after 1m0s
[05:47:17.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:77:0)
[05:47:17.724] Timer tick, asking Buildbucket for the build status
[05:47:18.142] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:18.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:78:0) after 1m0s
[05:48:18.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:78:0)
[05:48:18.690] Timer tick, asking Buildbucket for the build status
[05:48:18.794] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:18.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:79:0) after 1m0s
[05:49:18.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:79:0)
[05:49:18.873] Timer tick, asking Buildbucket for the build status
[05:49:18.960] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:18.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:80:0) after 1m0s
[05:50:19.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:80:0)
[05:50:19.120] Timer tick, asking Buildbucket for the build status
[05:50:19.184] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:81:0) after 1m0s
[05:51:19.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:81:0)
[05:51:19.204] Timer tick, asking Buildbucket for the build status
[05:51:19.328] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:19.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:82:0) after 1m0s
[05:52:19.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:82:0)
[05:52:19.521] Timer tick, asking Buildbucket for the build status
[05:52:19.751] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:19.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:83:0) after 1m0s
[05:53:19.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:83:0)
[05:53:19.805] Timer tick, asking Buildbucket for the build status
[05:53:19.871] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:19.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:84:0) after 1m0s
[05:54:19.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:84:0)
[05:54:19.894] Timer tick, asking Buildbucket for the build status
[05:54:20.003] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:20.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:85:0) after 1m0s
[05:55:20.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:85:0)
[05:55:20.240] Timer tick, asking Buildbucket for the build status
[05:55:20.305] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:20.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:86:0) after 1m0s
[05:56:21.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:86:0)
[05:56:21.216] Timer tick, asking Buildbucket for the build status
[05:56:21.846] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:21.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:87:0) after 1m0s
[05:57:21.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:87:0)
[05:57:21.918] Timer tick, asking Buildbucket for the build status
[05:57:22.016] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:22.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:88:0) after 1m0s
[05:58:22.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:88:0)
[05:58:22.056] Timer tick, asking Buildbucket for the build status
[05:58:22.144] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:22.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:89:0) after 1m0s
[05:59:23.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:89:0)
[05:59:23.748] Timer tick, asking Buildbucket for the build status
[05:59:23.915] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:90:0) after 1m0s
[06:00:23.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:90:0)
[06:00:23.943] Timer tick, asking Buildbucket for the build status
[06:00:24.083] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:24.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:91:0) after 1m0s
[06:01:26.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:91:0)
[06:01:26.478] Timer tick, asking Buildbucket for the build status
[06:01:26.551] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:26.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:92:0) after 1m0s
[06:02:26.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:92:0)
[06:02:26.624] Timer tick, asking Buildbucket for the build status
[06:02:26.781] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:26.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:93:0) after 1m0s
[06:03:26.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:93:0)
[06:03:26.845] Timer tick, asking Buildbucket for the build status
[06:03:26.965] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:26.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:94:0) after 1m0s
[06:04:26.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:94:0)
[06:04:26.995] Timer tick, asking Buildbucket for the build status
[06:04:27.172] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:27.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:95:0) after 1m0s
[06:05:27.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:95:0)
[06:05:27.234] Timer tick, asking Buildbucket for the build status
[06:05:27.616] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:27.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:96:0) after 1m0s
[06:06:27.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:96:0)
[06:06:27.703] Timer tick, asking Buildbucket for the build status
[06:06:28.725] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:28.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:97:0) after 1m0s
[06:07:28.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:97:0)
[06:07:28.768] Timer tick, asking Buildbucket for the build status
[06:07:29.354] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:29.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:98:0) after 1m0s
[06:08:29.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:98:0)
[06:08:29.437] Timer tick, asking Buildbucket for the build status
[06:08:29.526] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:29.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:99:0) after 1m0s
[06:09:29.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:99:0)
[06:09:29.546] Timer tick, asking Buildbucket for the build status
[06:09:29.643] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:29.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:100:0) after 1m0s
[06:10:29.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:100:0)
[06:10:29.662] Timer tick, asking Buildbucket for the build status
[06:10:29.745] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:29.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:101:0) after 1m0s
[06:11:29.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:101:0)
[06:11:29.832] Timer tick, asking Buildbucket for the build status
[06:11:30.275] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:30.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:102:0) after 1m0s
[06:12:30.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:102:0)
[06:12:30.322] Timer tick, asking Buildbucket for the build status
[06:12:30.462] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:30.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:103:0) after 1m0s
[06:13:30.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:103:0)
[06:13:30.531] Timer tick, asking Buildbucket for the build status
[06:13:30.814] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:30.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:104:0) after 1m0s
[06:14:30.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:104:0)
[06:14:30.850] Timer tick, asking Buildbucket for the build status
[06:14:32.035] Build 8857071395876699344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:32.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022501457853991552:105:0) after 1m0s
[06:15:25.273] Received PubSub notification, asking Buildbucket for the build status
[06:15:25.368] Build 8857071395876699344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:15:25.368] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611641724357424",
"created_by": "project:chromium",
"created_ts": "1611635513383861",
"failure_reason": "BUILD_FAILURE",
"id": "8857071395876699344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AdsPageLoadMetricsObserverBrowserTest.DisallowedAdFrames_NotMeasured\"}, \"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\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\", \"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-9-snau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17014, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"9bbcd86ae719866273598c988709c7ec3d462e1d\", \"got_dawn_revision\": \"73668344d656e4bad2f6a1dd75c85ffae1e56973\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\", \"got_revision_cp\": \"refs/heads/master@{#847052}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"99e54b1c5caf06a25f76faa4c20c9ab1b31bdb61\", \"got_v8_revision\": \"28f6c291a22caef35a8cf0b58ed0889dc8152903\", \"got_v8_revision_cp\": \"refs/heads/9.0.68@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"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\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\", \"swarm_hashes_refs/heads/master(at){#847052}_without_patch\": {\"absl_hardening_tests\": \"95b58bf8cdce404e7e673ab5540c0637b9171bb7\", \"accessibility_unittests\": \"0af986c0e6233500feeea8c64324297dc62a51c2\", \"angle_unittests\": \"a2e43ec7c3aa2e25386ae6b6a979e8f090e0cb99\", \"app_shell_unittests\": \"9b507ea7a4b10709ecea8133ad2c349c46663318\", \"aura_unittests\": \"168d23e5f7374634b5d80c18e6b8aaceef9e79b9\", \"base_unittests\": \"be427c26c129bb1e023aa74719ee734875d55dea\", \"base_util_unittests\": \"8a93e4acb71b98f2b4ab503d7fc2047591f73b64\", \"blink_common_unittests\": \"4697bd432452f43d412c886449f8175cafb7590b\", \"blink_fuzzer_unittests\": \"1c77be7ab0bf2bdfdd5c5b5813940b0d4d597ead\", \"blink_heap_unittests\": \"01a88c6694fa8c934a9db30f3dc70beadf9cea20\", \"blink_platform_unittests\": \"a3032669f85fae13311706a080ccc36b8ed52c78\", \"blink_unittests\": \"c075e5f5ee1412588b18927a8986ddced4ddf11d\", \"boringssl_crypto_tests\": \"8b8d4e06dcec0ccb3632bfc89449d90d3f74451f\", \"boringssl_ssl_tests\": \"85bdacf7a56383560f9e1dda0f00847b5bcf83a8\", \"browser_switcher_bho_unittests\": \"cd45ae66b56b56d21e41e81ee3db35118127e337\", \"browser_tests\": \"85dc3f55df1c11655f92e92b0a95ffef6d19e4a4\", \"capture_unittests\": \"8079b04ab55cc8ed10b3b5e6efeb7fa6a7abf5eb\", \"cast_unittests\": \"7ec7762ddb4c61e8a501b5c1e5a48bb27010496b\", \"cc_unittests\": \"ec928f9ae768ffd2f57626c0a77dbf8f91d0582a\", \"chrome_app_unittests\": \"9317b3e4fcb38ed309c97a9d8c6fef07514bc44d\", \"chrome_cleaner_unittests\": \"42f6e1d919147918492d6d715e9b3991fbb6825a\", \"chrome_elf_unittests\": \"9f81c04ab982574ae7895bed441a88fe9febf44f\", \"chromedriver_unittests\": \"28391e82323fc11acfb0c6ed0a47c8b9aaa2239a\", \"components_browsertests\": \"ccba17acb5bb0f00cfafe283bbde3284b9600af2\", \"components_unittests\": \"e0ca270faa0b0a31f2b3b3bf862e960b701c24d5\", \"compositor_unittests\": \"183d473a1c24a75294d868954e451fb517414eff\", \"content_browsertests\": \"2e5242cb39c4cbfe9f7e5b5247345032c7356793\", \"content_unittests\": \"3954c14eac801a0bc2311ba090d877f1f2d9b66e\", \"courgette_unittests\": \"25d5f19dc99fb14454e7538febb0e9725771b072\", \"crashpad_tests\": \"1309216c58b9af993b1c0b5aff2fe889b47ccb90\", \"cronet_tests\": \"497fc3d020bd6dc550e8c952549d4a71af9575e6\", \"cronet_unittests\": \"8db18e7d0e76ae990c22dd588b1cbff91d037808\", \"crypto_unittests\": \"c438d65665032e2805764b28fea8a73b0fdbdf0f\", \"delayloads_unittests\": \"0fba02a982f6aa216dcd4f051be12fb74b2582a3\", \"device_unittests\": \"e07cb8d620113c07064e7f8724156b66b79fcbe0\", \"display_unittests\": \"2b5bc9a119ecd7f5f27211e8b3d6c75a83f6ed0d\", \"elevation_service_unittests\": \"ce40dc121da0a1afaf1a37fc0ec811038ebd8199\", \"events_unittests\": \"30028e5a35269e250d4e1a1a3b505af7024765b5\", \"extensions_browsertests\": \"c85eef8621be0bf0e9e9de8fb3bb9c462d6656c0\", \"extensions_unittests\": \"62056d13b1cb9ebba10cdd4becb8a1b478ab50b5\", \"filesystem_service_unittests\": \"f0388b0f0e46c77d556f43578c5038f5f63a31d6\", \"gcm_unit_tests\": \"e15f471e69cc57beaa19840dc946bbc3b77c7bc1\", \"gcp_unittests\": \"eac238ac5d1fcc4dc3656ac61fb515ea83c95286\", \"gfx_unittests\": \"778874b0b8e5cacca09dcd6ba87a6c94ad7e40fd\", \"gin_unittests\": \"572d62983ab01ef7b72e30b537c14541591d8eba\", \"google_apis_unittests\": \"696ef0069a8a3f5b26e3c6dd88d88a96adab0330\", \"gpu_unittests\": \"b9548324fe8613c796d62e8c5dd8b914c71fb571\", \"gwp_asan_unittests\": \"972ff49dde130ecdf78732a3ce70638d65c2e4e9\", \"headless_browsertests\": \"62be2f4fe34a089e330dd2553f281fce651df958\", \"headless_unittests\": \"8c16cd195b86ee058e571675d26e6f56b5d7fbfe\", \"install_static_unittests\": \"61e49d47764ee27ec54a3d9c87f6433af93bd9c5\", \"installer_util_unittests\": \"4b03c10f72d72766d8b68e3617f3ab58e63e1a99\", \"interactive_ui_tests\": \"44f1941dfc4228008bd59cd9286e6d670f79925c\", \"ipc_tests\": \"be235cbad7458647223a367ea0234378cfd6d2a0\", \"jingle_unittests\": \"fd94821870f43a247b7d3c432263bb94c120ab38\", \"latency_unittests\": \"5c8ae1d2201610620cb06f992f63be365b113c81\", \"libjingle_xmpp_unittests\": \"bd621d53f98b3a31f33a3f2a517b0581420451bd\", \"liburlpattern_unittests\": \"d7c5a6bc7ef33511ba8396a5dd6c7189ee9e4288\", \"media_blink_unittests\": \"1fbcfe5fea0b6c6363ecccfa4e15ce4ae3047f8c\", \"media_unittests\": \"1f817cc914948f3808bbc519252e6781cdffdfc1\", \"message_center_unittests\": \"d7e05f161ac98b72510a165a17d187248b574703\", \"midi_unittests\": \"491f73466e059aa163f8be73516990a170b9b5e9\", \"mojo_core_unittests\": \"7d3a11bb5703f1969a5360dd47f4179a3aa043cc\", \"mojo_unittests\": \"8367ed36e3e9c8593fdc9795692ff3aba6997ab6\", \"nacl_loader_unittests\": \"19d719af9050c562a881487a156cefadd5512086\", \"native_theme_unittests\": \"cc090bc507c36737cb00dce3f660eaf05c430e92\", \"net_unittests\": \"57b85c87bc7f5ebb2ff5c8be73ac22c169385d18\", \"notification_helper_unittests\": \"19ad85df5f6b57847089e792e55d09742a94ff03\", \"pdf_unittests\": \"98a7543c7c5926a46f3a1f4008b0d82c33ebe0c4\", \"perfetto_unittests\": \"d1c312f0bd6cda1d0104e6fe3c45a795f03016da\", \"ppapi_unittests\": \"a536f7357794edda38477fb6c5a14559d92787c5\", \"printing_unittests\": \"77230037f145f76cb608fe9f112ba2a571ca00f0\", \"remoting_unittests\": \"ccd595c78f3aa2e8daee5d3504b00d41895e67e0\", \"sbox_integration_tests\": \"54f8430392120ab46639be8c2a1952d87c21998b\", \"sbox_unittests\": \"a999084c4021267847acf4f533bcd897e70f5bb1\", \"sbox_validation_tests\": \"e047e2c6c3e2aadb3d35e548993f0e6293f71628\", \"service_manager_unittests\": \"7611a17f4500f264dbf0160345d515783c505f0a\", \"services_unittests\": \"b4a688239f0ab4e3531102726a325457a7567012\", \"setup_unittests\": \"a6498e1ea7a4997ee0cd56b799936da599b783d0\", \"shell_dialogs_unittests\": \"4500c06873b4c5d96ce65da91c9c2341abbcccde\", \"skia_unittests\": \"efb812362f33c061e522bb2632c86bae65156dc7\", \"snapshot_unittests\": \"ad6ed66801162072f39af42abec6988de2cf023b\", \"sql_unittests\": \"0540816fef3490937a07a175062a16253109d9fe\", \"storage_unittests\": \"ff78ebc5e4488bb629aecc63b1dbfc86f869c66f\", \"sync_integration_tests\": \"641adcc44e53111c5d7252e2b90f4ee9688f0740\", \"traffic_annotation_auditor_unittests\": \"2eee3da842b3c2e19500ea74b31ff1a64797bffc\", \"ui_base_unittests\": \"ea6b621765595782f96a80a9e6abc2aaf14b6329\", \"ui_touch_selection_unittests\": \"578acd1532b6d71f5955d0983f93668f4c0d8b30\", \"unit_tests\": \"1b8a11e1bb2827b0a5382b59f770bb207765b9b7\", \"updater_tests\": \"e191c7de5a4b28ea31be865c64ef9cb34657d979\", \"url_unittests\": \"096014e35680e9d65092ed1985d6c1283b7ac4ca\", \"views_unittests\": \"debb6b48d2b6c3414cb944f9dd399eafdf2d7f06\", \"viz_unittests\": \"df1c9ce77546b84a24f05792976a5000f1a0ab85\", \"vr_common_unittests\": \"3480c46bf0ede3c1fbaaa19391752241ced028ae\", \"vr_pixeltests\": \"ed17b4552d531aedfeb049cf99e50745f9f6313c\", \"weblayer_browsertests\": \"742e8c14e262e99d6c1c8d1e3c4b6105338899fa\", \"weblayer_unittests\": \"7e31952458b63d90e0519fa189a68ce4189dd22b\", \"wm_unittests\": \"04275ba7bfbbda12eb688313573d23631158bb2e\", \"wtf_unittests\": \"39a1426e57f2b7641fab716b5b5f77e6d3a3f4a7\", \"zlib_unittests\": \"00af7d59f956e6ae4f04802bcf43cb7c7d77c41d\", \"zucchini_unittests\": \"c5a5694d667e5ac9d12382f429e2ae3384e01c56\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"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-9-snau\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5672-4713de1\"], \"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- AdsPageLoadMetricsObserverBrowserTest.DisallowedAdFrames_NotMeasured\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611635801795981",
"status": "COMPLETED",
"status_changed_ts": "1611635802218295",
"tags": [
"build_address:luci.chromium.ci/win-asan/17014",
"builder:win-asan",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022501457853991552",
"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/8857071395876699344/+/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:5155c7e3c9d9b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611641724826557",
"url": "https://ci.chromium.org/b/8857071395876699344",
"utcnow_ts": "1611641725359154"
}
}
[06:15:25.369] Invocation finished in 1h43m33.25481305s with status FAILED