[00:10:30.293] New invocation is queued and will start shortly
[00:10:31.369] Starting the invocation (attempt 1)
[00:10:31.416] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:10:31.417] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:10:31.417] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022880289318604704",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d600abac45b6c72ceef4c610d5e6b8796c04e17a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022880289318604704",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"gitiles_ref:refs/heads/master"
]
}
[00:10:32.481] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611274231525327",
"id": "8857450227362963424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d600abac45b6c72ceef4c610d5e6b8796c04e17a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611274232183543",
"tags": [
"build_address:luci.chromium.ci/win-asan/16940",
"builder:win-asan",
"buildset:commit/git/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022880289318604704",
"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/8857450227362963424/+/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": "1611274232183725",
"url": "https://ci.chromium.org/b/8857450227362963424",
"utcnow_ts": "1611274232467121"
}
}
[00:10:32.481] Task URL: https://ci.chromium.org/b/8857450227362963424
[00:10:32.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:2:0) after 1m0s
[00:10:35.308] Received PubSub notification, asking Buildbucket for the build status
[00:10:35.396] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:32.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:2:0)
[00:11:32.551] Timer tick, asking Buildbucket for the build status
[00:11:32.726] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:32.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:4:0) after 1m0s
[00:12:32.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:4:0)
[00:12:32.767] Timer tick, asking Buildbucket for the build status
[00:12:33.006] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:5:0) after 1m0s
[00:13:33.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:5:0)
[00:13:33.070] Timer tick, asking Buildbucket for the build status
[00:13:33.233] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:33.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:6:0) after 1m0s
[00:14:33.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:6:0)
[00:14:33.256] Timer tick, asking Buildbucket for the build status
[00:14:33.409] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:33.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:7:0) after 1m0s
[00:15:33.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:7:0)
[00:15:33.439] Timer tick, asking Buildbucket for the build status
[00:15:33.584] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:33.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:8:0) after 1m0s
[00:16:33.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:8:0)
[00:16:33.629] Timer tick, asking Buildbucket for the build status
[00:16:33.786] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:33.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:9:0) after 1m0s
[00:17:33.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:9:0)
[00:17:33.808] Timer tick, asking Buildbucket for the build status
[00:17:33.876] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:33.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:10:0) after 1m0s
[00:18:33.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:10:0)
[00:18:33.890] Timer tick, asking Buildbucket for the build status
[00:18:34.054] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:34.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:11:0) after 1m0s
[00:19:34.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:11:0)
[00:19:34.076] Timer tick, asking Buildbucket for the build status
[00:19:34.216] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:34.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:12:0) after 1m0s
[00:20:34.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:12:0)
[00:20:34.268] Timer tick, asking Buildbucket for the build status
[00:20:34.351] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:34.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:13:0) after 1m0s
[00:21:34.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:13:0)
[00:21:34.439] Timer tick, asking Buildbucket for the build status
[00:21:34.599] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:34.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:14:0) after 1m0s
[00:22:34.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:14:0)
[00:22:34.674] Timer tick, asking Buildbucket for the build status
[00:22:34.836] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:34.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:15:0) after 1m0s
[00:23:34.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:15:0)
[00:23:34.876] Timer tick, asking Buildbucket for the build status
[00:23:35.088] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:35.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:16:0) after 1m0s
[00:24:35.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:16:0)
[00:24:35.193] Timer tick, asking Buildbucket for the build status
[00:24:35.289] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:35.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:17:0) after 1m0s
[00:25:35.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:17:0)
[00:25:35.359] Timer tick, asking Buildbucket for the build status
[00:25:35.552] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:35.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:18:0) after 1m0s
[00:26:35.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:18:0)
[00:26:35.552] Timer tick, asking Buildbucket for the build status
[00:26:35.758] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:35.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:19:0) after 1m0s
[00:27:35.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:19:0)
[00:27:35.759] Timer tick, asking Buildbucket for the build status
[00:27:35.949] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:35.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:20:0) after 1m0s
[00:28:35.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:20:0)
[00:28:35.979] Timer tick, asking Buildbucket for the build status
[00:28:36.245] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:36.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:21:0) after 1m0s
[00:29:36.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:21:0)
[00:29:36.371] Timer tick, asking Buildbucket for the build status
[00:29:36.608] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:22:0) after 1m0s
[00:30:36.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:22:0)
[00:30:36.727] Timer tick, asking Buildbucket for the build status
[00:30:36.928] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:36.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:23:0) after 1m0s
[00:31:37.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:23:0)
[00:31:37.001] Timer tick, asking Buildbucket for the build status
[00:31:37.184] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:37.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:24:0) after 1m0s
[00:32:37.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:24:0)
[00:32:37.205] Timer tick, asking Buildbucket for the build status
[00:32:37.344] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:37.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:25:0) after 1m0s
[00:33:37.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:25:0)
[00:33:37.424] Timer tick, asking Buildbucket for the build status
[00:33:37.808] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:37.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:26:0) after 1m0s
[00:34:37.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:26:0)
[00:34:37.841] Timer tick, asking Buildbucket for the build status
[00:34:38.112] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:38.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:27:0) after 1m0s
[00:35:38.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:27:0)
[00:35:38.235] Timer tick, asking Buildbucket for the build status
[00:35:38.444] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:38.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:28:0) after 1m0s
[00:36:38.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:28:0)
[00:36:38.468] Timer tick, asking Buildbucket for the build status
[00:36:38.620] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:38.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:29:0) after 1m0s
[00:37:38.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:29:0)
[00:37:38.671] Timer tick, asking Buildbucket for the build status
[00:37:38.890] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:38.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:30:0) after 1m0s
[00:38:38.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:30:0)
[00:38:38.914] Timer tick, asking Buildbucket for the build status
[00:38:39.054] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:39.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:31:0) after 1m0s
[00:39:39.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:31:0)
[00:39:39.109] Timer tick, asking Buildbucket for the build status
[00:39:39.580] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:39.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:32:0) after 1m0s
[00:40:39.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:32:0)
[00:40:39.591] Timer tick, asking Buildbucket for the build status
[00:40:39.701] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:39.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:33:0) after 1m0s
[00:41:39.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:33:0)
[00:41:39.724] Timer tick, asking Buildbucket for the build status
[00:41:39.832] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:34:0) after 1m0s
[00:42:39.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:34:0)
[00:42:39.878] Timer tick, asking Buildbucket for the build status
[00:42:40.186] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:40.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:35:0) after 1m0s
[00:43:40.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:35:0)
[00:43:40.211] Timer tick, asking Buildbucket for the build status
[00:43:40.347] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:40.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:36:0) after 1m0s
[00:44:40.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:36:0)
[00:44:40.417] Timer tick, asking Buildbucket for the build status
[00:44:42.628] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:42.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:37:0) after 1m0s
[00:45:42.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:37:0)
[00:45:42.748] Timer tick, asking Buildbucket for the build status
[00:45:42.865] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:42.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:38:0) after 1m0s
[00:46:42.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:38:0)
[00:46:42.935] Timer tick, asking Buildbucket for the build status
[00:46:43.015] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:43.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:39:0) after 1m0s
[00:47:43.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:39:0)
[00:47:43.075] Timer tick, asking Buildbucket for the build status
[00:47:43.302] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:43.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:40:0) after 1m0s
[00:48:43.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:40:0)
[00:48:43.323] Timer tick, asking Buildbucket for the build status
[00:48:43.750] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:43.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:41:0) after 1m0s
[00:49:43.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:41:0)
[00:49:43.764] Timer tick, asking Buildbucket for the build status
[00:49:43.848] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:43.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:42:0) after 1m0s
[00:50:43.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:42:0)
[00:50:43.947] Timer tick, asking Buildbucket for the build status
[00:50:44.217] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:44.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:43:0) after 1m0s
[00:51:44.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:43:0)
[00:51:44.394] Timer tick, asking Buildbucket for the build status
[00:51:44.574] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:44.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:44:0) after 1m0s
[00:52:44.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:44:0)
[00:52:44.667] Timer tick, asking Buildbucket for the build status
[00:52:44.997] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:44.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:45:0) after 1m0s
[00:53:45.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:45:0)
[00:53:45.028] Timer tick, asking Buildbucket for the build status
[00:53:55.414] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:55.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:46:0) after 1m0s
[00:54:55.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:46:0)
[00:54:55.665] Timer tick, asking Buildbucket for the build status
[00:54:55.915] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:55.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:47:0) after 1m0s
[00:55:55.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:47:0)
[00:55:55.903] Timer tick, asking Buildbucket for the build status
[00:55:56.429] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:56.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:48:0) after 1m0s
[00:56:56.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:48:0)
[00:56:56.487] Timer tick, asking Buildbucket for the build status
[00:56:56.566] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:56.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:49:0) after 1m0s
[00:57:56.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:49:0)
[00:57:56.656] Timer tick, asking Buildbucket for the build status
[00:57:56.868] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:56.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:50:0) after 1m0s
[00:58:56.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:50:0)
[00:58:56.860] Timer tick, asking Buildbucket for the build status
[00:58:57.012] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:57.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:51:0) after 1m0s
[00:59:57.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:51:0)
[00:59:57.278] Timer tick, asking Buildbucket for the build status
[00:59:57.451] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:57.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:52:0) after 1m0s
[01:00:57.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:52:0)
[01:00:57.568] Timer tick, asking Buildbucket for the build status
[01:00:57.811] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:57.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:53:0) after 1m0s
[01:01:58.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:53:0)
[01:01:58.208] Timer tick, asking Buildbucket for the build status
[01:01:58.325] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:58.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:54:0) after 1m0s
[01:02:58.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:54:0)
[01:02:58.480] Timer tick, asking Buildbucket for the build status
[01:02:58.743] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:58.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:55:0) after 1m0s
[01:03:58.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:55:0)
[01:03:58.863] Timer tick, asking Buildbucket for the build status
[01:03:58.933] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:58.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:56:0) after 1m0s
[01:04:59.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:56:0)
[01:04:59.050] Timer tick, asking Buildbucket for the build status
[01:04:59.325] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:59.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:57:0) after 1m0s
[01:05:59.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:57:0)
[01:05:59.482] Timer tick, asking Buildbucket for the build status
[01:06:00.203] Build 8857450227362963424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:00.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022880289318604704:58:0) after 1m0s
[01:06:16.335] Received PubSub notification, asking Buildbucket for the build status
[01:06:16.416] Build 8857450227362963424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:06:16.416] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611277575648726",
"created_by": "project:chromium",
"created_ts": "1611274231525327",
"failure_reason": "BUILD_FAILURE",
"id": "8857450227362963424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d600abac45b6c72ceef4c610d5e6b8796c04e17a\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\"}, \"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\": \"d600abac45b6c72ceef4c610d5e6b8796c04e17a\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16940, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"203154b52a462070e774072abb3c71882bd9b61e\", \"got_dawn_revision\": \"001b499275e1354cc1198f5e7a50509aabe28be7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d600abac45b6c72ceef4c610d5e6b8796c04e17a\", \"got_revision_cp\": \"refs/heads/master@{#845840}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5b36fbc5ed2b330b2bc02c93ff2bdb58d03ae051\", \"got_v8_revision\": \"bba017b40897bdf1fed939a76260569fe84bdc71\", \"got_v8_revision_cp\": \"refs/heads/9.0.42@{#1}\", \"got_webrtc_revision\": \"6031b74664d0be40d7f913600d17f6d8d1323729\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33052}\", \"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\": \"d600abac45b6c72ceef4c610d5e6b8796c04e17a\", \"swarm_hashes_refs/heads/master(at){#845840}_without_patch\": {\"absl_hardening_tests\": \"cc84e14b8a36e2fb22919deb6b0421d74143eae4\", \"accessibility_unittests\": \"07102e26aada4d5977046faceff44be9724d41bc\", \"angle_unittests\": \"d61cdef9fcc80eb14c6190027df182a8d6f02f44\", \"app_shell_unittests\": \"210c8dc385977960765a7e87eacfb2803ec20066\", \"aura_unittests\": \"5f88f2567380849f9222dcec74675f1e867fa597\", \"base_unittests\": \"41b0450627a73fb460cdaa4f411780cf280116c7\", \"base_util_unittests\": \"dc082486244bc2521424fdf9d258c6b966a45c4b\", \"blink_common_unittests\": \"952297ddd9adc5cc3a8b8be57b3ced086607a32f\", \"blink_fuzzer_unittests\": \"dbb902281df7de36f83c4b3559e65e3b86c3ecad\", \"blink_heap_unittests\": \"94b3918f3cee5f42113bf7d2de719826822a7ea2\", \"blink_platform_unittests\": \"33e33b801dc06c210ae92f48cb93193f3308daa0\", \"blink_unittests\": \"d1aff4f81feea4859f263fae47845b30fd82eafe\", \"boringssl_crypto_tests\": \"a35b4ddc1516df0703e5330b080774a555275beb\", \"boringssl_ssl_tests\": \"34173fe65571f70f19c895dce5c35698723fd6cd\", \"browser_switcher_bho_unittests\": \"288fdabdfe1722237bb0ecc3634d8b95cbee37b3\", \"browser_tests\": \"f9267d8fb2e458c047ef45297f544a03a4754a2d\", \"capture_unittests\": \"45a9f70784a1dda629dcfff6975c8ee1c000b3a9\", \"cast_unittests\": \"fe1c2c0faf2af65420621ebb8c4e39dddb2c471d\", \"cc_unittests\": \"d84138685891c3e6efa7bcfeadcd571ca9de10f7\", \"chrome_app_unittests\": \"67c796a64c1e0f619bac44ece24bd974606048fd\", \"chrome_cleaner_unittests\": \"03b7a4913c9751a41e18658fbf8e52c41e0a4c29\", \"chrome_elf_unittests\": \"9de6a2f1d530bb82f1bdedf6f31d674fa9cf63e9\", \"chromedriver_unittests\": \"d34bf871ed345340664b49ce65485484c3a00fc3\", \"components_browsertests\": \"bdd800df7e66fbc958ed5d250b55bd43c18759a2\", \"components_unittests\": \"43aec25c600ebf38855d5816824d20e7d24c589f\", \"compositor_unittests\": \"10a73fd35af2a7125e11e2de1da177ac54cf23d2\", \"content_browsertests\": \"a61800b1b7be1978948db54ddf5ac5eb41e081aa\", \"content_unittests\": \"79c221a8864ca00bb9bc2b7841cde41315fb1a0d\", \"courgette_unittests\": \"68bd983f4ba031cfeca868efc294e563502b08ac\", \"crashpad_tests\": \"22b2f6c4e4d9d6b9f2ffdd2b718beb4224d49c1f\", \"cronet_tests\": \"744c2c92ebaae336d9ace1c2db91e9808ed2039d\", \"cronet_unittests\": \"8d2ae1930dff21708b5f04a4f7e190ec1714aef0\", \"crypto_unittests\": \"53b18150f862adde44a9cf8b500b2bb2682d0990\", \"delayloads_unittests\": \"57f3f2fb44ee7d27bef6c72c87b0958206316bc9\", \"device_unittests\": \"28c625101ac21b0b93a935cf1d758865434bcd54\", \"display_unittests\": \"868197d29804a005439b77bf81b17d25c5a1cde8\", \"elevation_service_unittests\": \"9135c36c9f0dd0afe99aa1e89381498ff4ae5151\", \"events_unittests\": \"83e83e93e90080641fa185960fcf3a57fe958f14\", \"extensions_browsertests\": \"2d3c28d32d4ab6176c8ba2a9d8bd99c0745e6150\", \"extensions_unittests\": \"9b27061cc4629ca80ee5b6fc7cbf0d0c11bddefe\", \"filesystem_service_unittests\": \"9a155c3399ef60e7558dd54beea646a478ed7061\", \"gcm_unit_tests\": \"eb40347b23a6a1a9a3a0d1028f25e8b91cebf865\", \"gcp_unittests\": \"ec43f9501d5fb745a7a05552d9526a428abd9d88\", \"gfx_unittests\": \"10f95285333d973d8ebe560d59d9f044271723a8\", \"gin_unittests\": \"0238c82f46447bc43e301f6a2a25ea3a2f44020a\", \"google_apis_unittests\": \"d3e24ca8197dc0169a19321e87993ea38995f9e1\", \"gpu_unittests\": \"3dd9ab3e18648258c076ba77aba1ec216f919035\", \"gwp_asan_unittests\": \"304a7a70830f6f6964cbab1db07063e33ee4a2ea\", \"headless_browsertests\": \"a593dcd30183f2b780472e6d167608ccd0e1d92b\", \"headless_unittests\": \"d8b7ef798bab123b246d31763193d3b9fd51fcd8\", \"install_static_unittests\": \"4e3c39644f933c59c34f042de272af7a2c5626df\", \"installer_util_unittests\": \"d50ed3997eec7112bcea048eccd00ce4439d8fbb\", \"interactive_ui_tests\": \"ad1c8f6406c450a1e4b9bb79b7b51000ea66acf1\", \"ipc_tests\": \"d481602efcbcad7ca8b1cbee154c1eacac3bc9b6\", \"jingle_unittests\": \"9d8ad1d36286fa5b818f62cbc4229d733f6fb8e2\", \"latency_unittests\": \"1807b81f5b25bb2796cfff6a43a2be04267da1ad\", \"libjingle_xmpp_unittests\": \"9bfd9f2855a4a17f83c3272aca5ed0a78740c638\", \"liburlpattern_unittests\": \"39a7eaaa3af2cbd769da5958062142cc71fafc6b\", \"media_blink_unittests\": \"249c69076b5ab257a92ea988fb5063e32bacec03\", \"media_unittests\": \"70a8e8786d1e05f3f27c4608d12f896a92965c58\", \"message_center_unittests\": \"a8e1b358355ba5dd01ec068aa465a97b7ff3f09a\", \"midi_unittests\": \"62780e7885e031d8d622f68d27bbf15c23438871\", \"mojo_core_unittests\": \"e3c85c73f911a8f376c83bdb0ba8649cecea9ff2\", \"mojo_unittests\": \"e3343293ad9813eb763464e1899bfac934788506\", \"nacl_loader_unittests\": \"f61a73831dd866e625b6ef249d648dee2d78a6ba\", \"native_theme_unittests\": \"2537daf43f2d7f19168a08735eef4ffa26d8122c\", \"net_unittests\": \"81658e3310079a948048cd2c1229731319b8826c\", \"notification_helper_unittests\": \"f39220576df938443159960385a707b8de3c87f6\", \"pdf_unittests\": \"4fa225220581d379ef404b5134e64dcdbaf87813\", \"perfetto_unittests\": \"925521275a23f32482c7ed9727f1c3c32becce04\", \"ppapi_unittests\": \"01c1979947ed5f2a7d41a8f0e1c8f1fd83eb3d45\", \"printing_unittests\": \"dbf53f732d088a5604790856c0dd00c2dc886409\", \"remoting_unittests\": \"cfa3636e17d110d48f3590b1dca56f3e564f5ee6\", \"sbox_integration_tests\": \"600e1c02c26aa41e5258f91599cc9cd079f39cbb\", \"sbox_unittests\": \"eb05f57adcc670af59736e5c1ff3ad95bfad9f9d\", \"sbox_validation_tests\": \"29c497293251f4e94887fe81f740644e5e856895\", \"service_manager_unittests\": \"8fad2f07fa0a8d1608e780fa74b74a3f19556e3c\", \"services_unittests\": \"52d040f02c53cdc6335ded6860b5a7ccf210f40e\", \"setup_unittests\": \"ed328947b9567cd814677aa929ec9e3054833ed4\", \"shell_dialogs_unittests\": \"360312088a03eb4fa610a729f748416926ffec54\", \"skia_unittests\": \"cd14e4d3f881f44120cbbb138230777e10601ad5\", \"snapshot_unittests\": \"8ffeef2420784f75db1c7da5ceae3905c830773a\", \"sql_unittests\": \"9240316117b610c6681a807263e102a3d4567f7b\", \"storage_unittests\": \"797d6328fa1355040c38e542393f11163eedee20\", \"sync_integration_tests\": \"47b56b6ff2e2e1f3d042d2149135538e1883c268\", \"traffic_annotation_auditor_unittests\": \"0e552c1b8a5e7025516c11ed524e9a6ec3ce070a\", \"ui_base_unittests\": \"c6d0282d0dc312903dad4a96cb82f658974e2774\", \"ui_touch_selection_unittests\": \"d89d009893d16d02ddaa7b5e5353c2cdcbeee2b5\", \"unit_tests\": \"744a83bacb51fba1e63a3fe4af1dd85099bae9bf\", \"updater_tests\": \"c8fa56a9fc862067ea75c5382f4c564031d1bd3e\", \"url_unittests\": \"a29aef00af88a93191d036388d2dea5ef117dfeb\", \"views_unittests\": \"03c1ac5f4f8578989a486d26d46686518250964a\", \"viz_unittests\": \"79b282a8da2c33f2e27eefb6b782889545ad1f05\", \"vr_common_unittests\": \"1b726da7694b17e7e516f77c19c98920fc77485e\", \"vr_pixeltests\": \"cad6c37adf5545c0401ce3eaeab91df894212403\", \"weblayer_browsertests\": \"d827a9ea7e0d6e5677000761ea1ceff07827b724\", \"weblayer_unittests\": \"3bc94ada14ca009ccc3524ddf63c0cdfac08b9d8\", \"wm_unittests\": \"09503e4789a0e69d40d816441f087c0db3de97d0\", \"wtf_unittests\": \"a83221a0f20f0b7bc3e10b8fa2d3c139de02cb8b\", \"zlib_unittests\": \"5deaed05e9b6e639afa9901193670e31b87499c2\", \"zucchini_unittests\": \"94217c9994f1578930ef39885ad82fdda0506dfc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611274234392339",
"status": "COMPLETED",
"status_changed_ts": "1611274234969468",
"tags": [
"build_address:luci.chromium.ci/win-asan/16940",
"builder:win-asan",
"buildset:commit/git/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d600abac45b6c72ceef4c610d5e6b8796c04e17a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022880289318604704",
"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/8857450227362963424/+/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:51403f2e7f352f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611277576043738",
"url": "https://ci.chromium.org/b/8857450227362963424",
"utcnow_ts": "1611277576406366"
}
}
[01:06:16.416] Invocation finished in 55m46.139740346s with status FAILED