[12:05:30.129] New invocation is queued and will start shortly
[12:05:31.225] Starting the invocation (attempt 1)
[12:05:31.293] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:05:31.293] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:05:31.293] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021204560188544064",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021204560188544064",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"gitiles_ref:refs/heads/master"
]
}
[12:05:32.303] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612872331423569",
"id": "8855774498163585776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612872332190367",
"tags": [
"build_address:luci.chromium.ci/win-asan/17254",
"builder:win-asan",
"buildset:commit/git/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021204560188544064",
"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/8855774498163585776/+/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": "1612872332192976",
"url": "https://ci.chromium.org/b/8855774498163585776",
"utcnow_ts": "1612872332296079"
}
}
[12:05:32.303] Task URL: https://ci.chromium.org/b/8855774498163585776
[12:05:32.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:2:0) after 1m0s
[12:05:36.054] Received PubSub notification, asking Buildbucket for the build status
[12:05:36.134] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:32.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:2:0)
[12:06:32.335] Timer tick, asking Buildbucket for the build status
[12:06:32.432] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:32.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:4:0) after 1m0s
[12:07:32.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:4:0)
[12:07:32.529] Timer tick, asking Buildbucket for the build status
[12:07:32.646] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:32.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:5:0) after 1m0s
[12:08:32.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:5:0)
[12:08:32.792] Timer tick, asking Buildbucket for the build status
[12:08:33.151] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:33.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:6:0) after 1m0s
[12:09:33.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:6:0)
[12:09:33.300] Timer tick, asking Buildbucket for the build status
[12:09:33.654] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:33.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:7:0) after 1m0s
[12:10:33.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:7:0)
[12:10:33.762] Timer tick, asking Buildbucket for the build status
[12:10:33.886] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:33.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:8:0) after 1m0s
[12:11:33.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:8:0)
[12:11:33.993] Timer tick, asking Buildbucket for the build status
[12:11:34.503] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:34.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:9:0) after 1m0s
[12:12:34.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:9:0)
[12:12:34.654] Timer tick, asking Buildbucket for the build status
[12:12:34.720] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:34.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:10:0) after 1m0s
[12:13:34.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:10:0)
[12:13:34.935] Timer tick, asking Buildbucket for the build status
[12:13:35.114] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:35.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:11:0) after 1m0s
[12:14:35.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:11:0)
[12:14:35.238] Timer tick, asking Buildbucket for the build status
[12:14:35.312] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:35.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:12:0) after 1m0s
[12:15:35.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:12:0)
[12:15:35.293] Timer tick, asking Buildbucket for the build status
[12:15:35.417] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:35.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:13:0) after 1m0s
[12:16:35.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:13:0)
[12:16:35.502] Timer tick, asking Buildbucket for the build status
[12:16:35.655] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:35.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:14:0) after 1m0s
[12:17:35.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:14:0)
[12:17:35.700] Timer tick, asking Buildbucket for the build status
[12:17:36.056] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:36.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:15:0) after 1m0s
[12:18:36.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:15:0)
[12:18:36.193] Timer tick, asking Buildbucket for the build status
[12:18:36.263] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:36.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:16:0) after 1m0s
[12:19:36.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:16:0)
[12:19:36.275] Timer tick, asking Buildbucket for the build status
[12:19:36.351] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:36.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:17:0) after 1m0s
[12:20:36.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:17:0)
[12:20:36.406] Timer tick, asking Buildbucket for the build status
[12:20:36.716] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:36.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:18:0) after 1m0s
[12:21:36.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:18:0)
[12:21:36.739] Timer tick, asking Buildbucket for the build status
[12:21:36.840] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:36.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:19:0) after 1m0s
[12:22:36.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:19:0)
[12:22:36.936] Timer tick, asking Buildbucket for the build status
[12:22:37.026] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:37.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:20:0) after 1m0s
[12:23:37.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:20:0)
[12:23:37.066] Timer tick, asking Buildbucket for the build status
[12:23:37.137] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:37.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:21:0) after 1m0s
[12:24:37.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:21:0)
[12:24:37.214] Timer tick, asking Buildbucket for the build status
[12:24:37.278] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:22:0) after 1m0s
[12:25:37.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:22:0)
[12:25:37.316] Timer tick, asking Buildbucket for the build status
[12:25:37.721] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:37.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:23:0) after 1m0s
[12:26:37.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:23:0)
[12:26:37.833] Timer tick, asking Buildbucket for the build status
[12:26:38.138] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:38.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:24:0) after 1m0s
[12:27:38.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:24:0)
[12:27:38.160] Timer tick, asking Buildbucket for the build status
[12:27:38.262] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:38.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:25:0) after 1m0s
[12:28:38.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:25:0)
[12:28:38.279] Timer tick, asking Buildbucket for the build status
[12:28:38.442] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:38.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:26:0) after 1m0s
[12:29:38.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:26:0)
[12:29:38.479] Timer tick, asking Buildbucket for the build status
[12:29:38.594] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:38.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:27:0) after 1m0s
[12:30:38.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:27:0)
[12:30:38.630] Timer tick, asking Buildbucket for the build status
[12:30:38.755] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:38.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:28:0) after 1m0s
[12:31:38.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:28:0)
[12:31:38.820] Timer tick, asking Buildbucket for the build status
[12:31:38.994] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:38.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:29:0) after 1m0s
[12:32:39.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:29:0)
[12:32:39.014] Timer tick, asking Buildbucket for the build status
[12:32:39.609] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:30:0) after 1m0s
[12:33:39.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:30:0)
[12:33:39.633] Timer tick, asking Buildbucket for the build status
[12:33:39.762] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:39.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:31:0) after 1m0s
[12:34:39.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:31:0)
[12:34:39.783] Timer tick, asking Buildbucket for the build status
[12:34:39.905] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:39.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:32:0) after 1m0s
[12:35:40.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:32:0)
[12:35:40.000] Timer tick, asking Buildbucket for the build status
[12:35:40.104] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:40.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:33:0) after 1m0s
[12:36:40.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:33:0)
[12:36:40.129] Timer tick, asking Buildbucket for the build status
[12:36:40.245] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:40.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:34:0) after 1m0s
[12:37:40.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:34:0)
[12:37:40.282] Timer tick, asking Buildbucket for the build status
[12:37:40.481] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:40.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:35:0) after 1m0s
[12:38:40.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:35:0)
[12:38:40.525] Timer tick, asking Buildbucket for the build status
[12:38:41.057] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:41.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:36:0) after 1m0s
[12:39:41.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:36:0)
[12:39:41.040] Timer tick, asking Buildbucket for the build status
[12:39:41.449] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:41.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:37:0) after 1m0s
[12:40:41.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:37:0)
[12:40:41.496] Timer tick, asking Buildbucket for the build status
[12:40:41.873] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:41.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:38:0) after 1m0s
[12:41:41.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:38:0)
[12:41:41.903] Timer tick, asking Buildbucket for the build status
[12:41:41.998] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:41.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:39:0) after 1m0s
[12:42:42.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:39:0)
[12:42:42.017] Timer tick, asking Buildbucket for the build status
[12:42:42.079] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:42.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:40:0) after 1m0s
[12:43:42.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:40:0)
[12:43:42.100] Timer tick, asking Buildbucket for the build status
[12:43:42.179] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:42.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:41:0) after 1m0s
[12:44:42.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:41:0)
[12:44:42.211] Timer tick, asking Buildbucket for the build status
[12:44:42.269] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:42.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:42:0) after 1m0s
[12:45:42.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:42:0)
[12:45:42.278] Timer tick, asking Buildbucket for the build status
[12:45:42.783] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:42.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:43:0) after 1m0s
[12:46:42.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:43:0)
[12:46:42.839] Timer tick, asking Buildbucket for the build status
[12:46:42.934] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:42.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:44:0) after 1m0s
[12:47:42.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:44:0)
[12:47:42.957] Timer tick, asking Buildbucket for the build status
[12:47:43.068] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:43.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:45:0) after 1m0s
[12:48:43.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:45:0)
[12:48:43.058] Timer tick, asking Buildbucket for the build status
[12:48:43.735] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:43.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:46:0) after 1m0s
[12:49:43.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:46:0)
[12:49:43.809] Timer tick, asking Buildbucket for the build status
[12:49:43.917] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:43.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:47:0) after 1m0s
[12:50:43.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:47:0)
[12:50:43.932] Timer tick, asking Buildbucket for the build status
[12:50:44.297] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:44.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:48:0) after 1m0s
[12:51:44.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:48:0)
[12:51:44.304] Timer tick, asking Buildbucket for the build status
[12:51:44.425] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:44.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:49:0) after 1m0s
[12:52:44.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:49:0)
[12:52:44.483] Timer tick, asking Buildbucket for the build status
[12:52:44.651] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:44.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:50:0) after 1m0s
[12:53:44.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:50:0)
[12:53:44.673] Timer tick, asking Buildbucket for the build status
[12:53:44.805] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:44.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:51:0) after 1m0s
[12:54:44.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:51:0)
[12:54:44.877] Timer tick, asking Buildbucket for the build status
[12:54:45.204] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:45.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:52:0) after 1m0s
[12:55:45.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:52:0)
[12:55:45.236] Timer tick, asking Buildbucket for the build status
[12:55:45.352] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:45.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:53:0) after 1m0s
[12:56:45.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:53:0)
[12:56:45.376] Timer tick, asking Buildbucket for the build status
[12:56:45.441] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:45.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:54:0) after 1m0s
[12:57:45.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:54:0)
[12:57:45.488] Timer tick, asking Buildbucket for the build status
[12:57:45.561] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:45.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:55:0) after 1m0s
[12:58:45.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:55:0)
[12:58:45.690] Timer tick, asking Buildbucket for the build status
[12:58:45.821] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:45.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:56:0) after 1m0s
[12:59:45.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:56:0)
[12:59:45.995] Timer tick, asking Buildbucket for the build status
[12:59:46.073] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:46.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:57:0) after 1m0s
[13:00:46.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:57:0)
[13:00:46.094] Timer tick, asking Buildbucket for the build status
[13:00:46.263] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:46.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:58:0) after 1m0s
[13:01:46.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:58:0)
[13:01:46.373] Timer tick, asking Buildbucket for the build status
[13:01:46.500] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:46.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:59:0) after 1m0s
[13:02:46.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:59:0)
[13:02:46.601] Timer tick, asking Buildbucket for the build status
[13:02:47.235] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:47.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:60:0) after 1m0s
[13:03:47.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:60:0)
[13:03:47.257] Timer tick, asking Buildbucket for the build status
[13:03:47.349] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:47.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:61:0) after 1m0s
[13:04:47.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:61:0)
[13:04:47.461] Timer tick, asking Buildbucket for the build status
[13:04:47.740] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:47.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:62:0) after 1m0s
[13:05:47.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:62:0)
[13:05:47.754] Timer tick, asking Buildbucket for the build status
[13:05:47.904] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:47.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:63:0) after 1m0s
[13:06:47.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:63:0)
[13:06:47.999] Timer tick, asking Buildbucket for the build status
[13:06:48.103] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:48.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:64:0) after 1m0s
[13:07:48.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:64:0)
[13:07:48.212] Timer tick, asking Buildbucket for the build status
[13:07:48.287] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:48.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:65:0) after 1m0s
[13:08:48.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:65:0)
[13:08:48.671] Timer tick, asking Buildbucket for the build status
[13:08:49.111] Build 8855774498163585776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:49.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021204560188544064:66:0) after 1m0s
[13:09:32.770] Received PubSub notification, asking Buildbucket for the build status
[13:09:32.846] Build 8855774498163585776: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:09:32.846] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612876171453805",
"created_by": "project:chromium",
"created_ts": "1612872331423569",
"failure_reason": "BUILD_FAILURE",
"id": "8855774498163585776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"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\": \"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\", \"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-8-jw2s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17254, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"80335a11165f7809a736429f0a5e13bd63e09765\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\", \"got_revision_cp\": \"refs/heads/master@{#852160}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"9d35d544ce966098fdcb36107835d57a4dc1f32d\", \"got_v8_revision\": \"b19b2448ed8ecdc8965621750ac648a2331c0c18\", \"got_v8_revision_cp\": \"refs/heads/9.0.144@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"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\": \"8a4f7c9321d7bf6427a11d5f7c955ea007155f5e\", \"swarm_hashes_refs/heads/master(at){#852160}_without_patch\": {\"absl_hardening_tests\": \"f331f07e4f044c08d5c0d20f693676c4c1280438\", \"accessibility_unittests\": \"8044bbf58d858a484b4306e5d928b61651c85cca\", \"angle_unittests\": \"e3909920f379b2f34e029a89fdae37ab77f9629e\", \"app_shell_unittests\": \"81716393f622f08912c2923d21dae444d5f485bd\", \"aura_unittests\": \"89f781660a3741beab9fe7de3803b8c9c4a5e34e\", \"base_unittests\": \"355e7776a2dfd3e20782622902cac5c9a4a03ea5\", \"base_util_unittests\": \"7836d726d566757266ca0f74aa0b8e9ceabffe6f\", \"blink_common_unittests\": \"b2bb0c9fd440ef224d4210703ccc484b4ac8b035\", \"blink_fuzzer_unittests\": \"16c417d7c3e6dfaa3b6ed08ca9f69a70103bcd93\", \"blink_heap_unittests\": \"cecf3e569b83c9aaff24573402c125b293c4b072\", \"blink_platform_unittests\": \"66eea0e00bda5849bcf2c4e8a7552cc10bd8ed28\", \"blink_unittests\": \"e851b88266ace5b4cd07441e6464a73c63a49b09\", \"boringssl_crypto_tests\": \"911304cf904d33614972214a63a87322a1e112a0\", \"boringssl_ssl_tests\": \"9475bd0d9cbfe45413a1c9d5ccc8c06685e90752\", \"browser_switcher_bho_unittests\": \"7058291fe52e761a0205ec7b04f1597874ca0236\", \"browser_tests\": \"ca0c603b71be0e910590ea742bda057e9f70bf1d\", \"capture_unittests\": \"f5fef40f74c3c8b84d1f5cf706c082c63e420484\", \"cast_unittests\": \"532d4b199621fb5a509b647106115e406a48b5b5\", \"cc_unittests\": \"3efd2458f3529d0d8a73693e03475251dc846778\", \"chrome_app_unittests\": \"d65e9ecc0e33b2321fd7b3b55cbdc6d34215a583\", \"chrome_cleaner_unittests\": \"ac9191a246951be32c661f5198b7b15e2295b124\", \"chrome_elf_unittests\": \"5d5dfac8fc9ec9f36b04eef0998f3f8a8f21de4a\", \"chromedriver_unittests\": \"f6e27d6f62c4994e5d90fc565b1742da8b25d6e6\", \"components_browsertests\": \"e38b40e5f67f3da5774e92841adb75331a09d0ec\", \"components_unittests\": \"1eec2d9e96945265ba6e87a4e22c7746cfad109e\", \"compositor_unittests\": \"50630618b80a26ed00febe0576fcb9c2034859a2\", \"content_browsertests\": \"6f1d1a861694c5dab4baa58c571766051b0a7743\", \"content_unittests\": \"cfe8bc4dbb4bb8bdba2c267e15f386ef4ce518e5\", \"courgette_unittests\": \"87d32dea05815b1174d2e84749c4d9cacc6ab5b2\", \"crashpad_tests\": \"6fb1526f275802064f63b61493ea8e0f331e4d56\", \"cronet_tests\": \"1b1e74f82a0555e71f97482123e504ef4e57434c\", \"cronet_unittests\": \"9aef8fa3f8ddced33885a380feafc95b290cbb52\", \"crypto_unittests\": \"0f201dd55aef6809f632c65053c1e37bd82b26b4\", \"delayloads_unittests\": \"c530f09c23c4e748efee497aaca36a47c06d3d5f\", \"device_unittests\": \"b8975910783adc5057a946234d995adeccd1eabe\", \"display_unittests\": \"bff8206ea1621bf9b9f9aaff62e92ed59881a33d\", \"elevation_service_unittests\": \"6718d0376281dd8e3ab89c8a76664718ba242edf\", \"events_unittests\": \"6c7b1695905cc8060dfcca8597b83cd6c2d30982\", \"extensions_browsertests\": \"5590019098e8fbed3b294bc16cfb9285cb3c8660\", \"extensions_unittests\": \"0302cc6fdde9fa25a1296c036e4f6914ed716468\", \"filesystem_service_unittests\": \"9ec2b7db0717684d76b80bd51f7d16e14c098187\", \"gcm_unit_tests\": \"495a33dcb8c033b3f12c374211e8f9a4867f3228\", \"gcp_unittests\": \"a9ad9d501caa8507b4795430e1db21834b25070a\", \"gfx_unittests\": \"618c708a37fe7ac03929a2ed9fb989a53b1f0910\", \"gin_unittests\": \"0ec65cae1ed9ded1be84b2c0e27f5945b7073e44\", \"google_apis_unittests\": \"7e43ecd08078d965cc4b3941928b8293b836f9f8\", \"gpu_unittests\": \"542854365fac3d38ca1576ac908c5ccecbf262da\", \"gwp_asan_unittests\": \"156ee908928ea6952f6263e20bf296f0b3bff850\", \"headless_browsertests\": \"8aaf706a7e32a2d154855fedbe64915064c34d5b\", \"headless_unittests\": \"306bbe17fed9d021b01ce9164fb14e972bc884a5\", \"install_static_unittests\": \"99636eeb7242f3f49034460fbbbdf7dd0f34829e\", \"installer_util_unittests\": \"0195476f12bef0435aa26adef2b2e3bb98194a32\", \"interactive_ui_tests\": \"01a291d356e8b2bf8e19b6bcd38f588d4f90e6ca\", \"ipc_tests\": \"099f0ae01ad3960efb110e173cf4d35fb6f2c455\", \"jingle_unittests\": \"3616fb98d027a9f2d7b8fa8856d39d31032f1fb3\", \"latency_unittests\": \"6466db07c978e68db6ce9f7c0f7777d4260de28e\", \"libjingle_xmpp_unittests\": \"0d71a6aba8bbae36633a43291510bbca0c27b3a5\", \"liburlpattern_unittests\": \"ad593f62064d240095e5074f003b80fab4134ca8\", \"media_blink_unittests\": \"f857d7450d7a3bb81d9bf4286906312196196e50\", \"media_unittests\": \"b288308e2a60bb095a7dae66ab19749ff58c1e2d\", \"message_center_unittests\": \"78cdbabdf2a23500762ff97f3078322c239a7da9\", \"midi_unittests\": \"0696ff047d751677bdbafdb867d50f4345176680\", \"mojo_core_unittests\": \"ba6590fa2625f65a18c0454c12cfec227e1d6eda\", \"mojo_unittests\": \"ebc6b988825493d3d859f15996faa62e326ab6b3\", \"nacl_loader_unittests\": \"3b4df69f7e5336b6179ff5f541e6e62fb510de94\", \"native_theme_unittests\": \"43b86aa38a21bbd35d179be57ac3ba32746a2ca6\", \"net_unittests\": \"ebfb394b95732d2e748c00bf37e2cbd1f11aff96\", \"notification_helper_unittests\": \"d05867a6294c8620e60bdb0c6b1631f626d2e06b\", \"pdf_unittests\": \"019861d136b76156d9d3876636c1da079abb7db8\", \"perfetto_unittests\": \"f543f582118a9110adbf9eef37e24c5f1068ab5c\", \"ppapi_unittests\": \"91add944b1c69022c953b096762fca988a1fed00\", \"printing_unittests\": \"30d4d770e388af1f35627d8d7badd5b5c28b91d2\", \"remoting_unittests\": \"3deb3d95a041a54cc799997ffaf5546ca011dd8d\", \"sbox_integration_tests\": \"d54c43f26216b11f7b347418b866628090d973cd\", \"sbox_unittests\": \"a7ffac1c2bdf296e359ff62247d4f54ef97d07e6\", \"sbox_validation_tests\": \"169a995a596dd8941fabb1b723a9aa340679f19c\", \"service_manager_unittests\": \"e56a90c39b0372694de6e272192d14bf6cdd46ae\", \"services_unittests\": \"8c7610927218cc8f0851111b80cc4ab4d73f8007\", \"setup_unittests\": \"4f6dec7b1df78856990febf8eb66adfbdc714a50\", \"shell_dialogs_unittests\": \"380b219a562de8217a58787a913e2efa860c09ee\", \"skia_unittests\": \"a9fad134c9840f9ac1fc99555db737edb5fd0323\", \"snapshot_unittests\": \"82c2933e9bc017c3cf1bac44f2e5dfa8d2545eb4\", \"sql_unittests\": \"1bf0618e639208cdc4fb05c781b4560723a1ebb6\", \"storage_unittests\": \"c3d169a3f228d3f69c3932761f66db88d922cede\", \"sync_integration_tests\": \"6063e8d450cfb6a458bdcd8a8f53f1472fb669d2\", \"traffic_annotation_auditor_unittests\": \"18abf95296726d10437c3c33a66df746c9287465\", \"ui_base_unittests\": \"ec5a24bcde9c38c675faa2ceb2b749f81bc65d1a\", \"ui_touch_selection_unittests\": \"1442ca989c2c0289064d3d1ee26102cda63b26bd\", \"unit_tests\": \"c3eb0bfac92556e530849f49d0dfe909e2597274\", \"updater_tests\": \"3875c1f4cba146896f6f9710ff734f393d63fce0\", \"url_unittests\": \"f1fa5f09d62680717cdab275a559092e75f0af14\", \"views_unittests\": \"3728c5f07cacb1d4a9fbb21c56ad787e789724d8\", \"viz_unittests\": \"db72a8fbcce153573c7ad022f8cc911c402eb1b7\", \"vr_common_unittests\": \"9bccfd5b17fa8fd7e713dfd19cb2e756a1f24aa1\", \"vr_pixeltests\": \"f7c5dce0abb068c6255d476044ffdb976e824d1e\", \"weblayer_browsertests\": \"0fbae6abad1c5639c52fab9da943cab94d3fbc97\", \"weblayer_unittests\": \"6119271df749f1fd458f7581fd3c1ecf06215fe3\", \"wm_unittests\": \"dd6a849fef43ef8765f2f69a149c6126219c3d86\", \"wtf_unittests\": \"c4d2f4c3493e328f1433f4b7f617273e088c0749\", \"zlib_unittests\": \"1e93b0009accfca348e2bfab05834c119feed9dd\", \"zucchini_unittests\": \"d3db0455926873c5516fdd54f258d610aa865ff0\"}}, \"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-8-jw2s\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612872335005593",
"status": "COMPLETED",
"status_changed_ts": "1612872335729065",
"tags": [
"build_address:luci.chromium.ci/win-asan/17254",
"builder:win-asan",
"buildset:commit/git/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a4f7c9321d7bf6427a11d5f7c955ea007155f5e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021204560188544064",
"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/8855774498163585776/+/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:519f803c9fa69e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612876172275557",
"url": "https://ci.chromium.org/b/8855774498163585776",
"utcnow_ts": "1612876172837641"
}
}
[13:09:32.846] Invocation finished in 1h4m2.737364772s with status FAILED