[15:10:42.665] New invocation is queued and will start shortly
[15:10:43.750] Starting the invocation (attempt 1)
[15:10:43.782] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:10:43.782] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:10:43.782] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018746789752130848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ae603f35a189f1c8fde0c847e318913e35cdbe03\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018746789752130848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"gitiles_ref:refs/heads/master"
]
}
[15:10:44.639] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615216243902395",
"id": "8853316727792803904",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ae603f35a189f1c8fde0c847e318913e35cdbe03\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615216244372238",
"tags": [
"build_address:luci.chromium.ci/win-asan/17704",
"builder:win-asan",
"buildset:commit/git/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018746789752130848",
"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/8853316727792803904/+/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": "1615216244372341",
"url": "https://ci.chromium.org/b/8853316727792803904",
"utcnow_ts": "1615216244633127"
}
}
[15:10:44.639] Task URL: https://ci.chromium.org/b/8853316727792803904
[15:10:44.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:2:0) after 1m0s
[15:11:19.016] Received PubSub notification, asking Buildbucket for the build status
[15:11:19.099] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:44.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:2:0)
[15:11:44.668] Timer tick, asking Buildbucket for the build status
[15:11:44.739] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:44.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:4:0) after 1m0s
[15:12:44.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:4:0)
[15:12:44.860] Timer tick, asking Buildbucket for the build status
[15:12:45.051] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:45.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:5:0) after 1m0s
[15:13:45.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:5:0)
[15:13:45.110] Timer tick, asking Buildbucket for the build status
[15:13:45.172] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:45.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:6:0) after 1m0s
[15:14:45.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:6:0)
[15:14:45.182] Timer tick, asking Buildbucket for the build status
[15:14:45.613] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:45.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:7:0) after 1m0s
[15:15:45.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:7:0)
[15:15:45.600] Timer tick, asking Buildbucket for the build status
[15:15:46.082] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:46.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:8:0) after 1m0s
[15:16:46.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:8:0)
[15:16:46.132] Timer tick, asking Buildbucket for the build status
[15:16:46.489] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:46.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:9:0) after 1m0s
[15:17:46.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:9:0)
[15:17:46.861] Timer tick, asking Buildbucket for the build status
[15:17:46.951] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:46.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:10:0) after 1m0s
[15:18:47.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:10:0)
[15:18:47.066] Timer tick, asking Buildbucket for the build status
[15:18:47.132] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:47.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:11:0) after 1m0s
[15:19:47.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:11:0)
[15:19:47.219] Timer tick, asking Buildbucket for the build status
[15:19:47.290] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:47.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:12:0) after 1m0s
[15:20:47.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:12:0)
[15:20:47.334] Timer tick, asking Buildbucket for the build status
[15:20:47.642] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:47.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:13:0) after 1m0s
[15:21:47.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:13:0)
[15:21:47.714] Timer tick, asking Buildbucket for the build status
[15:21:47.832] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:47.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:14:0) after 1m0s
[15:22:47.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:14:0)
[15:22:47.886] Timer tick, asking Buildbucket for the build status
[15:22:47.989] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:47.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:15:0) after 1m0s
[15:23:48.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:15:0)
[15:23:48.172] Timer tick, asking Buildbucket for the build status
[15:23:48.443] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:48.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:16:0) after 1m0s
[15:24:48.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:16:0)
[15:24:48.605] Timer tick, asking Buildbucket for the build status
[15:24:48.758] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:48.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:17:0) after 1m0s
[15:25:48.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:17:0)
[15:25:48.824] Timer tick, asking Buildbucket for the build status
[15:25:49.011] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:49.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:18:0) after 1m0s
[15:26:49.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:18:0)
[15:26:49.034] Timer tick, asking Buildbucket for the build status
[15:26:49.177] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:49.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:19:0) after 1m0s
[15:27:49.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:19:0)
[15:27:49.234] Timer tick, asking Buildbucket for the build status
[15:27:49.318] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:49.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:20:0) after 1m0s
[15:28:49.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:20:0)
[15:28:49.336] Timer tick, asking Buildbucket for the build status
[15:28:49.491] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:49.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:21:0) after 1m0s
[15:29:49.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:21:0)
[15:29:49.692] Timer tick, asking Buildbucket for the build status
[15:29:50.774] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:50.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:22:0) after 1m0s
[15:30:50.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:22:0)
[15:30:50.811] Timer tick, asking Buildbucket for the build status
[15:30:51.653] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:51.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:23:0) after 1m0s
[15:31:51.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:23:0)
[15:31:51.669] Timer tick, asking Buildbucket for the build status
[15:31:51.761] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:51.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:24:0) after 1m0s
[15:32:52.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:24:0)
[15:32:52.271] Timer tick, asking Buildbucket for the build status
[15:32:52.489] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:52.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:25:0) after 1m0s
[15:33:52.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:25:0)
[15:33:52.783] Timer tick, asking Buildbucket for the build status
[15:33:53.039] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:53.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:26:0) after 1m0s
[15:34:53.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:26:0)
[15:34:53.321] Timer tick, asking Buildbucket for the build status
[15:34:53.502] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:53.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:27:0) after 1m0s
[15:35:53.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:27:0)
[15:35:53.763] Timer tick, asking Buildbucket for the build status
[15:35:53.944] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:53.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:28:0) after 1m0s
[15:36:54.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:28:0)
[15:36:54.061] Timer tick, asking Buildbucket for the build status
[15:36:54.150] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:54.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:29:0) after 1m0s
[15:37:54.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:29:0)
[15:37:54.178] Timer tick, asking Buildbucket for the build status
[15:37:54.344] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:54.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:30:0) after 1m0s
[15:38:54.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:30:0)
[15:38:54.513] Timer tick, asking Buildbucket for the build status
[15:38:54.659] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:31:0) after 1m0s
[15:39:54.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:31:0)
[15:39:54.677] Timer tick, asking Buildbucket for the build status
[15:39:54.776] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:54.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:32:0) after 1m0s
[15:40:54.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:32:0)
[15:40:54.792] Timer tick, asking Buildbucket for the build status
[15:40:55.988] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:55.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:33:0) after 1m0s
[15:41:56.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:33:0)
[15:41:56.023] Timer tick, asking Buildbucket for the build status
[15:41:56.955] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:56.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:34:0) after 1m0s
[15:42:57.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:34:0)
[15:42:57.000] Timer tick, asking Buildbucket for the build status
[15:42:57.219] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:35:0) after 1m0s
[15:43:57.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:35:0)
[15:43:57.302] Timer tick, asking Buildbucket for the build status
[15:43:57.557] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:57.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:36:0) after 1m0s
[15:44:57.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:36:0)
[15:44:57.671] Timer tick, asking Buildbucket for the build status
[15:44:58.022] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:58.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:37:0) after 1m0s
[15:45:58.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:37:0)
[15:45:58.051] Timer tick, asking Buildbucket for the build status
[15:45:58.212] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:58.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:38:0) after 1m0s
[15:46:58.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:38:0)
[15:46:58.420] Timer tick, asking Buildbucket for the build status
[15:46:58.502] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:58.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:39:0) after 1m0s
[15:47:58.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:39:0)
[15:47:58.546] Timer tick, asking Buildbucket for the build status
[15:47:58.634] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:58.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:40:0) after 1m0s
[15:48:58.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:40:0)
[15:48:58.721] Timer tick, asking Buildbucket for the build status
[15:48:58.800] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:58.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:41:0) after 1m0s
[15:49:58.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:41:0)
[15:49:58.900] Timer tick, asking Buildbucket for the build status
[15:49:59.029] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:59.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:42:0) after 1m0s
[15:50:59.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:42:0)
[15:50:59.040] Timer tick, asking Buildbucket for the build status
[15:50:59.160] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:59.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:43:0) after 1m0s
[15:51:59.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:43:0)
[15:51:59.217] Timer tick, asking Buildbucket for the build status
[15:51:59.288] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:59.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:44:0) after 1m0s
[15:52:59.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:44:0)
[15:52:59.353] Timer tick, asking Buildbucket for the build status
[15:53:00.432] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:00.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:45:0) after 1m0s
[15:54:00.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:45:0)
[15:54:00.822] Timer tick, asking Buildbucket for the build status
[15:54:00.909] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:00.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:46:0) after 1m0s
[15:55:01.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:46:0)
[15:55:01.094] Timer tick, asking Buildbucket for the build status
[15:55:01.245] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:01.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:47:0) after 1m0s
[15:56:01.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:47:0)
[15:56:01.643] Timer tick, asking Buildbucket for the build status
[15:56:01.732] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:01.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:48:0) after 1m0s
[15:57:01.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:48:0)
[15:57:01.851] Timer tick, asking Buildbucket for the build status
[15:57:01.948] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:01.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:49:0) after 1m0s
[15:58:01.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:49:0)
[15:58:01.948] Timer tick, asking Buildbucket for the build status
[15:58:02.066] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:02.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:50:0) after 1m0s
[15:59:02.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:50:0)
[15:59:02.092] Timer tick, asking Buildbucket for the build status
[15:59:02.198] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:02.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:51:0) after 1m0s
[16:00:02.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:51:0)
[16:00:02.304] Timer tick, asking Buildbucket for the build status
[16:00:02.765] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:52:0) after 1m0s
[16:01:02.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:52:0)
[16:01:02.853] Timer tick, asking Buildbucket for the build status
[16:01:03.036] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:03.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:53:0) after 1m0s
[16:02:03.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:53:0)
[16:02:03.064] Timer tick, asking Buildbucket for the build status
[16:02:03.205] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:03.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:54:0) after 1m0s
[16:03:03.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:54:0)
[16:03:03.212] Timer tick, asking Buildbucket for the build status
[16:03:03.563] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:55:0) after 1m0s
[16:04:03.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:55:0)
[16:04:03.674] Timer tick, asking Buildbucket for the build status
[16:04:04.032] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:04.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:56:0) after 1m0s
[16:05:04.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:56:0)
[16:05:04.122] Timer tick, asking Buildbucket for the build status
[16:05:07.910] Build 8853316727792803904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:07.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018746789752130848:57:0) after 1m0s
[16:05:52.271] Received PubSub notification, asking Buildbucket for the build status
[16:05:52.718] Build 8853316727792803904: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:05:52.718] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615219551460257",
"created_by": "project:chromium",
"created_ts": "1615216243902395",
"failure_reason": "BUILD_FAILURE",
"id": "8853316727792803904",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ae603f35a189f1c8fde0c847e318913e35cdbe03\"}}",
"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\": \"ae603f35a189f1c8fde0c847e318913e35cdbe03\", \"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-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17704, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-1\", \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"ae603f35a189f1c8fde0c847e318913e35cdbe03\", \"got_revision_cp\": \"refs/heads/master@{#860717}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"583f60f2586a32144238698efe5490b34938a9d3\", \"got_v8_revision_cp\": \"refs/heads/9.1.61@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"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\": \"ae603f35a189f1c8fde0c847e318913e35cdbe03\", \"swarm_hashes_refs/heads/master(at){#860717}_without_patch\": {\"absl_hardening_tests\": \"d4839ba9ba86aa9ffadb155ff50ff33ad1c9c273\", \"accessibility_unittests\": \"1d99ae5179bb3d24441ac202d01541759f0e0502\", \"angle_unittests\": \"75f9bc528c302ac620423c70f3150893ba7ed744\", \"app_shell_unittests\": \"c35b7be8e5fbacf88138ec2221111cd1fc9f687b\", \"aura_unittests\": \"77add4a63e93a727281385ff3b2fbb18829ef6cb\", \"base_unittests\": \"9d23cf0f37d4dce3b1e9fb288cde938f3ebb429a\", \"base_util_unittests\": \"6c7aff3a502fbe72fe994697271be85155c44887\", \"blink_common_unittests\": \"13679c5c04d4688558af34c9a259b9261c66b098\", \"blink_fuzzer_unittests\": \"31e4fce2f9fd5fb0df16cce74a97b07cc641572a\", \"blink_heap_unittests\": \"90065c1229f1fe2cb33391b9f5f00a3433504d26\", \"blink_platform_unittests\": \"a97236980e5251eb83ceaa3fe7fa40f8d300ee28\", \"blink_unittests\": \"51dc2f97ad1f693713864b46e6063306f7abe9f7\", \"boringssl_crypto_tests\": \"2a54773cc316a3fed584710b7273f5223b1da399\", \"boringssl_ssl_tests\": \"90202be014d41cfbac1d84ca0b7e1b6b14a02286\", \"browser_switcher_bho_unittests\": \"529d8dd3a3b0c3b32ee3c0de2b753ccd52096b5c\", \"browser_tests\": \"ecf3ef61c7f8b24482c0878107ba443e6f639d55\", \"capture_unittests\": \"128dadac194ba5488cdf31ad6a375d357888a569\", \"cast_unittests\": \"41ef050deba025c55b1688dfdd92bbf27777d5e7\", \"cc_unittests\": \"bb657e3e41291c11ed3faf7c8fcb6786abac5402\", \"chrome_app_unittests\": \"55d5fdce090625c9fdb46231a0c2c99092fa6078\", \"chrome_cleaner_unittests\": \"9c51146347a18af3988a3f1b631e5dff6727a70e\", \"chrome_elf_unittests\": \"598f10f7ad17576bc8891465145f4a5cb402228e\", \"chromedriver_unittests\": \"2c113aaad54c6df8528f760179f4ce159d4d17e3\", \"color_unittests\": \"93adf28313ebccea6d7c1bdc760ade24925841b5\", \"components_browsertests\": \"afcb76ffac0db3a449dbbf9b6b73db54bc582549\", \"components_unittests\": \"b050b25a608eef7ee7bb79b4841796b35fc09f93\", \"compositor_unittests\": \"c4c03d65eba58ae130d51028b51738bb35ae09b8\", \"content_browsertests\": \"ce2232008760c9542e2a0ed85591c7689e2a565c\", \"content_unittests\": \"76ab29ce7904e7c54132e27fbbf952ed10d15fd3\", \"courgette_unittests\": \"453de8095e9ebf6ab3ff0c4ae5c27ff16ce89cf2\", \"crashpad_tests\": \"9777fe37a2c0f60e8fe2d80b331cd74544c4c20b\", \"cronet_tests\": \"0f1bab4a7e27e32c69e0797ec4741a67242b9447\", \"cronet_unittests\": \"3781c68f70f6944a330965b606af3353f79d422a\", \"crypto_unittests\": \"d41e10803caa055afe2d8c898d999ad308dcdec7\", \"delayloads_unittests\": \"4858a4919484ffcfb0ca912660136e30a9f92325\", \"device_unittests\": \"e59d14c0603f4a844fe2a3bb72d3bfee45b78817\", \"display_unittests\": \"9b4221ac5247896c3d3d06de9bfb9f2498ff0f11\", \"elevation_service_unittests\": \"5d1db5dc73de69a7c9a5e587e23ca38bc2a4ad27\", \"events_unittests\": \"04e1dd1829a2e22977265e40e35355004dd91315\", \"extensions_browsertests\": \"5e84cc78850a7ba0667f921f9e65036c1a2a5033\", \"extensions_unittests\": \"55695d40dbf3385b792697b9740a14226426210f\", \"filesystem_service_unittests\": \"d3222b0d2b8ebf89e1c9fde6dd1aaf6597604c32\", \"gcm_unit_tests\": \"a9e6bfbeb33266d6166ef26dc41db379f8b3a81b\", \"gcp_unittests\": \"c83d6bb341b202cc86e5826b5bf1ad30c08b6a9f\", \"gfx_unittests\": \"292201f378f7b7feaa9d588c45c0a480eef21a09\", \"gin_unittests\": \"05159df5e91f319cdc23489767596cfe8f474bc6\", \"google_apis_unittests\": \"88ff0bd5fa0f678ac2209d2c78fd9ab6cb572c92\", \"gpu_unittests\": \"c59d15591b9363b5f392e30c0be3fdaf39c43a21\", \"gwp_asan_unittests\": \"91f6d5380ef34bca005ed20808e0c31e3ee771af\", \"headless_browsertests\": \"a8b0c61d9d70b4d02dfe299df764c2d0837e3519\", \"headless_unittests\": \"66ca895cf5e7926c3e440fd3ecccc203942508d6\", \"install_static_unittests\": \"1a0c5695fc9b32889281a95cfc641ab1bf07d641\", \"installer_util_unittests\": \"1d4cc4dd0364af7acc42af8b70702e5672dabbbb\", \"interactive_ui_tests\": \"a4958ad373c8f65914488f85732bd8180f103842\", \"ipc_tests\": \"067a52d165efca269b360fbe1bd8b1e740ea8811\", \"jingle_unittests\": \"b4267e7d57d3155adab40accfedbcbd2d131d73b\", \"latency_unittests\": \"0cd31ed5b708694f9dbeb55a8566ba6cb049a44e\", \"libjingle_xmpp_unittests\": \"c456698b7c143598953d02e550b1bbb47f3452b3\", \"liburlpattern_unittests\": \"e0603e4eabf5056a04ccb5fc4cfe148534046ecb\", \"media_blink_unittests\": \"4946d4cafb5322599baae38b85cc635896cd81a2\", \"media_unittests\": \"ca45793774d247707b11a79fd0ee05f431e64332\", \"message_center_unittests\": \"f9ca9266980bf34d504ace50b9bb0a37eadf0524\", \"midi_unittests\": \"703fbe32c825fbd92a5921c434be721151dfe4b9\", \"mojo_core_unittests\": \"0195c8fe41db7da16fe5f27046763f6a17dcc7d3\", \"mojo_unittests\": \"7c9a87d0b409fc348e7fa3eaad34be08e3ebb339\", \"nacl_loader_unittests\": \"e7074b2a33c8d1e19b0670d18f44273bfb6b889a\", \"native_theme_unittests\": \"51250239068e4b8facc0cdb6e953e4de269b0afb\", \"net_unittests\": \"9c85afcb85d66658eadbb5c83c8ef2c34459fa4b\", \"notification_helper_unittests\": \"adcffb6e83d9041d08fde70a50ea317daacfb5d8\", \"pdf_unittests\": \"6fc4518ab926b319e0147a73c2e7e7f33dbca949\", \"perfetto_unittests\": \"ea1a2aff5fdd3a93a416aac0d1fe9248df7945f4\", \"ppapi_unittests\": \"159fc4ec4c453301cc3d632c3ae244675416af40\", \"printing_unittests\": \"1486e04d7f515ddcf53c5c9b691b62dc306bdd1e\", \"remoting_unittests\": \"ca4ae18af77232863576ab8c7e72d0b7f8d42f42\", \"sbox_integration_tests\": \"0c22f9887979fc514e1b4e953b33701666b241de\", \"sbox_unittests\": \"020e1ba2a12d67758e03088ad717d51a469d5c86\", \"sbox_validation_tests\": \"768e021832eaa38bba256529e61321f040e11ac7\", \"service_manager_unittests\": \"31f843094bd8c2ddbf00aec98db771c645455a58\", \"services_unittests\": \"b8a8580368eb73c39bdc21c8816acdd029ddd40a\", \"setup_unittests\": \"ab1646167e405b17d490e73efc81a9fefa733297\", \"shell_dialogs_unittests\": \"1547dcdb74e0f1373a87b2e39696c5c18520fb5d\", \"skia_unittests\": \"26aa25f77f0dee811a4fc872c28cd72fa8466ea7\", \"snapshot_unittests\": \"7be6004739c96f26fa808d872327bc3cefb62a72\", \"sql_unittests\": \"7ea02dd40ad27d26abe661d9fbe41056d238e2c4\", \"storage_unittests\": \"3cbb223d6d1a102973925dab33c9a191eeb90c39\", \"sync_integration_tests\": \"406bba5db0fd36a1a96212e19c2d9d70b60c81b0\", \"traffic_annotation_auditor_unittests\": \"f0ae2c233c53eb1784a7f0f5eca1575c498f0a0a\", \"ui_base_unittests\": \"c4295055af8e5257f6a69955fc10fd7d2d2fce25\", \"ui_touch_selection_unittests\": \"9f034f6d41ada4ec775d3e476ea959c983b984b4\", \"unit_tests\": \"6ab6d561757c10152ec1e583cda5caffdb1494a5\", \"updater_tests\": \"eed6e3f08c9866d432adbc88abb68e4ad4865ed3\", \"url_unittests\": \"ae5c8f19552eca2df39f13252b6b62ebcb138fc7\", \"views_unittests\": \"a1b5cbbed33a525288304ec1d0231f3af1081aa4\", \"viz_unittests\": \"8f04117f75f8996abed9a88953e00abe5e2ac2a1\", \"vr_common_unittests\": \"1ba9a44cf34c4dcca55ae7d112fdc0bfd86d27c0\", \"vr_pixeltests\": \"b1a22df7089ac3f0878630decb28faaf011025ae\", \"weblayer_browsertests\": \"7f66e6ffa6cf3cb3d012082a0ce2f7b56950d627\", \"weblayer_unittests\": \"f618b81dabdc2a4bdad725fece115a6e806d2c71\", \"wm_unittests\": \"60cd2ffe66a67bc92fff974f192676316f036d66\", \"wtf_unittests\": \"7df49b0c41a90af4f709b6ad88a0081949cac1a5\", \"zlib_unittests\": \"ee0bbbcd7952f3ab36bc94006a1536ca4e741575\", \"zucchini_unittests\": \"245349eaafcab3ca1afee1bfc7607b23fc73e1ac\"}}, \"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-2xaw\"], \"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\": [\"5744-5c0a4b4\"], \"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": "1615216277401619",
"status": "COMPLETED",
"status_changed_ts": "1615216278737240",
"tags": [
"build_address:luci.chromium.ci/win-asan/17704",
"builder:win-asan",
"buildset:commit/git/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae603f35a189f1c8fde0c847e318913e35cdbe03",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018746789752130848",
"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/8853316727792803904/+/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:522b35804d9efb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615219551461062",
"url": "https://ci.chromium.org/b/8853316727792803904",
"utcnow_ts": "1615219552664486"
}
}
[16:05:52.719] Invocation finished in 55m10.06840686s with status FAILED