[09:46:14.967] New invocation is queued and will start shortly
[09:46:16.189] Starting the invocation (attempt 1)
[09:46:16.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:16.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:16.225] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023750036266585360",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2c2fdc7c8a8030cc97012e30164f27653574c1a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023750036266585360",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"gitiles_ref:refs/heads/master"
]
}
[09:46:16.966] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610444776332164",
"id": "8858319974171399328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2c2fdc7c8a8030cc97012e30164f27653574c1a2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610444776835319",
"tags": [
"build_address:luci.chromium.ci/win-asan/16774",
"builder:win-asan",
"buildset:commit/git/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023750036266585360",
"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/8858319974171399328/+/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": "1610444776835460",
"url": "https://ci.chromium.org/b/8858319974171399328",
"utcnow_ts": "1610444776957485"
}
}
[09:46:16.966] Task URL: https://ci.chromium.org/b/8858319974171399328
[09:46:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:2:0) after 1m0s
[09:46:20.249] Received PubSub notification, asking Buildbucket for the build status
[09:46:20.388] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:16.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:2:0)
[09:47:16.983] Timer tick, asking Buildbucket for the build status
[09:47:18.670] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:18.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:4:0) after 1m0s
[09:48:18.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:4:0)
[09:48:18.726] Timer tick, asking Buildbucket for the build status
[09:48:18.833] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:18.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:5:0) after 1m0s
[09:49:18.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:5:0)
[09:49:18.833] Timer tick, asking Buildbucket for the build status
[09:49:18.982] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:18.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:6:0) after 1m0s
[09:50:19.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:6:0)
[09:50:19.048] Timer tick, asking Buildbucket for the build status
[09:50:19.167] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:19.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:7:0) after 1m0s
[09:51:19.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:7:0)
[09:51:19.222] Timer tick, asking Buildbucket for the build status
[09:51:19.333] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:19.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:8:0) after 1m0s
[09:52:19.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:8:0)
[09:52:19.369] Timer tick, asking Buildbucket for the build status
[09:52:19.608] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:9:0) after 1m0s
[09:53:19.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:9:0)
[09:53:19.628] Timer tick, asking Buildbucket for the build status
[09:53:19.745] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:19.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:10:0) after 1m0s
[09:54:19.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:10:0)
[09:54:19.800] Timer tick, asking Buildbucket for the build status
[09:54:19.866] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:19.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:11:0) after 1m0s
[09:55:19.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:11:0)
[09:55:19.974] Timer tick, asking Buildbucket for the build status
[09:55:20.270] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:20.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:12:0) after 1m0s
[09:56:20.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:12:0)
[09:56:20.293] Timer tick, asking Buildbucket for the build status
[09:56:20.418] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:20.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:13:0) after 1m0s
[09:57:20.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:13:0)
[09:57:20.411] Timer tick, asking Buildbucket for the build status
[09:57:20.798] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:20.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:14:0) after 1m0s
[09:58:20.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:14:0)
[09:58:20.899] Timer tick, asking Buildbucket for the build status
[09:58:21.115] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:21.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:15:0) after 1m0s
[09:59:21.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:15:0)
[09:59:21.139] Timer tick, asking Buildbucket for the build status
[09:59:21.420] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:21.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:16:0) after 1m0s
[10:00:21.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:16:0)
[10:00:21.451] Timer tick, asking Buildbucket for the build status
[10:00:21.834] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:21.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:17:0) after 1m0s
[10:01:21.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:17:0)
[10:01:21.903] Timer tick, asking Buildbucket for the build status
[10:01:22.012] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:22.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:18:0) after 1m0s
[10:02:22.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:18:0)
[10:02:22.036] Timer tick, asking Buildbucket for the build status
[10:02:22.119] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:22.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:19:0) after 1m0s
[10:03:22.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:19:0)
[10:03:22.137] Timer tick, asking Buildbucket for the build status
[10:03:22.258] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:22.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:20:0) after 1m0s
[10:04:22.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:20:0)
[10:04:22.288] Timer tick, asking Buildbucket for the build status
[10:04:22.645] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:22.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:21:0) after 1m0s
[10:05:22.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:21:0)
[10:05:22.685] Timer tick, asking Buildbucket for the build status
[10:05:22.989] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:22.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:22:0) after 1m0s
[10:06:23.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:22:0)
[10:06:23.057] Timer tick, asking Buildbucket for the build status
[10:06:23.212] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:23:0) after 1m0s
[10:07:23.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:23:0)
[10:07:23.402] Timer tick, asking Buildbucket for the build status
[10:07:23.660] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:23.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:24:0) after 1m0s
[10:08:23.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:24:0)
[10:08:23.709] Timer tick, asking Buildbucket for the build status
[10:08:23.947] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:23.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:25:0) after 1m0s
[10:09:24.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:25:0)
[10:09:24.004] Timer tick, asking Buildbucket for the build status
[10:09:24.113] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:24.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:26:0) after 1m0s
[10:10:24.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:26:0)
[10:10:24.180] Timer tick, asking Buildbucket for the build status
[10:10:24.427] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:24.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:27:0) after 1m0s
[10:11:24.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:27:0)
[10:11:24.474] Timer tick, asking Buildbucket for the build status
[10:11:24.626] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:24.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:28:0) after 1m0s
[10:12:24.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:28:0)
[10:12:24.650] Timer tick, asking Buildbucket for the build status
[10:12:24.779] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:24.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:29:0) after 1m0s
[10:13:24.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:29:0)
[10:13:24.808] Timer tick, asking Buildbucket for the build status
[10:13:24.980] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:24.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:30:0) after 1m0s
[10:14:25.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:30:0)
[10:14:25.014] Timer tick, asking Buildbucket for the build status
[10:14:25.193] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:25.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:31:0) after 1m0s
[10:15:25.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:31:0)
[10:15:25.223] Timer tick, asking Buildbucket for the build status
[10:15:25.361] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:25.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:32:0) after 1m0s
[10:16:25.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:32:0)
[10:16:25.389] Timer tick, asking Buildbucket for the build status
[10:16:25.504] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:25.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:33:0) after 1m0s
[10:17:25.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:33:0)
[10:17:25.531] Timer tick, asking Buildbucket for the build status
[10:17:25.739] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:25.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:34:0) after 1m0s
[10:18:25.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:34:0)
[10:18:25.736] Timer tick, asking Buildbucket for the build status
[10:18:25.875] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:25.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:35:0) after 1m0s
[10:19:25.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:35:0)
[10:19:25.890] Timer tick, asking Buildbucket for the build status
[10:19:26.017] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:26.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:36:0) after 1m0s
[10:20:26.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:36:0)
[10:20:26.009] Timer tick, asking Buildbucket for the build status
[10:20:26.143] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:26.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:37:0) after 1m0s
[10:21:26.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:37:0)
[10:21:26.169] Timer tick, asking Buildbucket for the build status
[10:21:26.330] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:26.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:38:0) after 1m0s
[10:22:26.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:38:0)
[10:22:26.366] Timer tick, asking Buildbucket for the build status
[10:22:26.544] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:26.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:39:0) after 1m0s
[10:23:26.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:39:0)
[10:23:26.656] Timer tick, asking Buildbucket for the build status
[10:23:26.747] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:26.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:40:0) after 1m0s
[10:24:26.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:40:0)
[10:24:26.915] Timer tick, asking Buildbucket for the build status
[10:24:27.041] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:27.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:41:0) after 1m0s
[10:25:27.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:41:0)
[10:25:27.311] Timer tick, asking Buildbucket for the build status
[10:25:27.541] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:27.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:42:0) after 1m0s
[10:26:27.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:42:0)
[10:26:27.647] Timer tick, asking Buildbucket for the build status
[10:26:27.778] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:27.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:43:0) after 1m0s
[10:27:28.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:43:0)
[10:27:28.096] Timer tick, asking Buildbucket for the build status
[10:27:28.220] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:28.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:44:0) after 1m0s
[10:28:28.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:44:0)
[10:28:28.273] Timer tick, asking Buildbucket for the build status
[10:28:28.390] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:28.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:45:0) after 1m0s
[10:29:28.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:45:0)
[10:29:28.533] Timer tick, asking Buildbucket for the build status
[10:29:28.605] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:28.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:46:0) after 1m0s
[10:30:28.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:46:0)
[10:30:28.708] Timer tick, asking Buildbucket for the build status
[10:30:28.807] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:28.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:47:0) after 1m0s
[10:31:28.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:47:0)
[10:31:28.837] Timer tick, asking Buildbucket for the build status
[10:31:28.958] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:28.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:48:0) after 1m0s
[10:32:29.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:48:0)
[10:32:29.003] Timer tick, asking Buildbucket for the build status
[10:32:29.070] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:29.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:49:0) after 1m0s
[10:33:29.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:49:0)
[10:33:29.214] Timer tick, asking Buildbucket for the build status
[10:33:29.816] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:29.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:50:0) after 1m0s
[10:34:29.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:50:0)
[10:34:29.867] Timer tick, asking Buildbucket for the build status
[10:34:30.359] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:30.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:51:0) after 1m0s
[10:35:30.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:51:0)
[10:35:30.355] Timer tick, asking Buildbucket for the build status
[10:35:30.450] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:30.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:52:0) after 1m0s
[10:36:30.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:52:0)
[10:36:30.524] Timer tick, asking Buildbucket for the build status
[10:36:30.674] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:30.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:53:0) after 1m0s
[10:37:30.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:53:0)
[10:37:30.733] Timer tick, asking Buildbucket for the build status
[10:37:31.737] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:31.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:54:0) after 1m0s
[10:38:31.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:54:0)
[10:38:31.774] Timer tick, asking Buildbucket for the build status
[10:38:31.864] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:31.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:55:0) after 1m0s
[10:39:31.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:55:0)
[10:39:31.922] Timer tick, asking Buildbucket for the build status
[10:39:32.086] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:32.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:56:0) after 1m0s
[10:40:32.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:56:0)
[10:40:32.073] Timer tick, asking Buildbucket for the build status
[10:40:32.133] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:32.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:57:0) after 1m0s
[10:41:32.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:57:0)
[10:41:32.159] Timer tick, asking Buildbucket for the build status
[10:41:32.307] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:32.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:58:0) after 1m0s
[10:42:32.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:58:0)
[10:42:32.361] Timer tick, asking Buildbucket for the build status
[10:42:32.525] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:32.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:59:0) after 1m0s
[10:43:32.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:59:0)
[10:43:32.671] Timer tick, asking Buildbucket for the build status
[10:43:32.747] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:32.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:60:0) after 1m0s
[10:44:32.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:60:0)
[10:44:32.880] Timer tick, asking Buildbucket for the build status
[10:44:32.972] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:32.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:61:0) after 1m0s
[10:45:33.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:61:0)
[10:45:33.067] Timer tick, asking Buildbucket for the build status
[10:45:33.139] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:33.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:62:0) after 1m0s
[10:46:33.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:62:0)
[10:46:33.179] Timer tick, asking Buildbucket for the build status
[10:46:33.342] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:33.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:63:0) after 1m0s
[10:47:33.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:63:0)
[10:47:33.517] Timer tick, asking Buildbucket for the build status
[10:47:33.591] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:33.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:64:0) after 1m0s
[10:48:33.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:64:0)
[10:48:33.692] Timer tick, asking Buildbucket for the build status
[10:48:33.821] Build 8858319974171399328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:33.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023750036266585360:65:0) after 1m0s
[10:48:48.668] Received PubSub notification, asking Buildbucket for the build status
[10:48:48.762] Build 8858319974171399328: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:48:48.762] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610448527393732",
"created_by": "project:chromium",
"created_ts": "1610444776332164",
"failure_reason": "BUILD_FAILURE",
"id": "8858319974171399328",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2c2fdc7c8a8030cc97012e30164f27653574c1a2\"}}",
"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\": \"2c2fdc7c8a8030cc97012e30164f27653574c1a2\", \"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-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16774, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"bdbcbe94074d9c656639f5837c2689acb395fdbd\", \"got_dawn_revision\": \"2bd95f1cf45451119b6644e41919b7bef443d912\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"2c2fdc7c8a8030cc97012e30164f27653574c1a2\", \"got_revision_cp\": \"refs/heads/master@{#842451}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"0e5a5d241dd9b54d5634df5c5582e86b54101492\", \"got_v8_revision_cp\": \"refs/heads/8.9.245@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"2c2fdc7c8a8030cc97012e30164f27653574c1a2\", \"swarm_hashes_refs/heads/master(at){#842451}_without_patch\": {\"absl_hardening_tests\": \"39947578cad4b4944d238c2e290f470ecd715e06\", \"accessibility_unittests\": \"dec1a01151333fece8f6763e82645c92cb557616\", \"angle_unittests\": \"c71220652fdbe5d01d681f206fff3e137b089542\", \"app_shell_unittests\": \"3dab704fabe6aa9660b329802b66aa7135072156\", \"aura_unittests\": \"9e5a0b268b726fab503bd2f75c1f30798aa89015\", \"base_unittests\": \"d80cf3383e5fa318f984f3818fcc0e7cd066a560\", \"base_util_unittests\": \"800ba2cc0e6a277e72c249fe37a7d999f3929c7e\", \"blink_common_unittests\": \"7612784113c4fba19224659de144cd12dd6510df\", \"blink_fuzzer_unittests\": \"53732d26604d310134b6ae6dbd08fde9e829a8a1\", \"blink_heap_unittests\": \"dfbd4eaa42ce8e2d1fbac8cf7baaf62c3ff31251\", \"blink_platform_unittests\": \"37287f2f8434504023477ce9c5417ecdcf1c3562\", \"blink_unittests\": \"54e92e97bdcc1a23d622abe729c235c37cd0b93c\", \"boringssl_crypto_tests\": \"170dbbe5a69c12612507a65ff9eab6a6d9a94e6e\", \"boringssl_ssl_tests\": \"846d68e10ac80200edc8c8161b0f05e14a1cedb5\", \"browser_switcher_bho_unittests\": \"ebba3240b94c1ff09a05e3d559f51d8d76023665\", \"browser_tests\": \"c646877d8d8e3855a2fc72cb05b8641800291d5a\", \"capture_unittests\": \"c1d48a2f58b8b26e73d33d82636e11acbf5acca3\", \"cast_unittests\": \"7c417cd7e19320c6c45404d0fd1997b518c51067\", \"cc_unittests\": \"1d3b3288bf8cb944033198ab3c2f4c9c2f91cd16\", \"chrome_app_unittests\": \"c3ee6e02023f2d076d2f9f2ca681e238fbaaeec3\", \"chrome_cleaner_unittests\": \"11a2c38695dd08ea15ad9c34b1911687a7ae4b2b\", \"chrome_elf_unittests\": \"36450cb9bcc3eac5ed3b01c89d76b9ebef192824\", \"chromedriver_unittests\": \"9d80052333d143a37a453a2d4e4b2728947dfac6\", \"components_browsertests\": \"47f63ce5f3cbca9a321caf21a67cfa417535d89b\", \"components_unittests\": \"9ed85acd878aa8f91e03a71fcea545cb68783e0f\", \"compositor_unittests\": \"b99da741a6b63ab831115be9e7f6205b3b02ec8c\", \"content_browsertests\": \"85c251378a7419b9e8c23b08799b6a57df32ce04\", \"content_unittests\": \"dfcadf0dfbc93511e03bbdd6c384745f4e279128\", \"courgette_unittests\": \"762ca72eb01e26642a15fac137d2d1da13646abd\", \"crashpad_tests\": \"ab26af0352985d1a4f58c4afb2b8ba2026f2d6ec\", \"cronet_tests\": \"433c66bb2812fbc312a928645c7f229d8b33ef80\", \"cronet_unittests\": \"8dc99d4c9856be6337af4ad5f94bf5215f9bd6f4\", \"crypto_unittests\": \"a5fca417f6faef9bc3ccaee3d292f3e62a371e55\", \"delayloads_unittests\": \"04892ac4fb57ff631a32fc23dff1ef945ec755a3\", \"device_unittests\": \"b284ae9513069337206e39be47a7d92e0edf248c\", \"display_unittests\": \"2a04d21a97b6355676361daf9aff0cea3d58e5ef\", \"elevation_service_unittests\": \"dadea914f15375f0ba9dfed22c19e0ebe3aa00f4\", \"events_unittests\": \"13dee4e801b26a3bb2635b274fb9c949734fa25f\", \"extensions_browsertests\": \"d87c04956708b029c256cd3765bee651301f1d33\", \"extensions_unittests\": \"0ffaaa6b8238aef7a01558712069ebd7a28c9cfa\", \"filesystem_service_unittests\": \"149fded819ea8a085e99d2c39c5b0c0de7a7a7fa\", \"gcm_unit_tests\": \"17256b6279a1303bd00cc839974ded1b64362e6c\", \"gcp_unittests\": \"0e996745071ffb082e740653b5cdf4fdd7c7abc2\", \"gfx_unittests\": \"4f417eb17ea9814be95d2456f2ae91df8b2279bc\", \"gin_unittests\": \"fba28d276ddbd7af9b5e83488378bc6ae7960683\", \"google_apis_unittests\": \"0929a6fd6bd6f52a87f55fd5aa7a7c86417e7fb0\", \"gpu_unittests\": \"1d8ce0006facc9d98ca7b0b2cec91299831a7bd9\", \"gwp_asan_unittests\": \"b5335895f33b4abb0188adec793609987f65d04b\", \"headless_browsertests\": \"6ce424166f6f88f080ebd12d915ddd423da4b0f8\", \"headless_unittests\": \"1681914bd3f95d0da678fcbe72a7ca35dd8f0dcb\", \"install_static_unittests\": \"9e64838934cecf142619169de6faa581ec4ded18\", \"installer_util_unittests\": \"df6a8da7405d197592c5d76184bb3e88db3c8990\", \"interactive_ui_tests\": \"efe250909e19dac0a5ad7e10211fd310a1ed1a4a\", \"ipc_tests\": \"86c7479f2d11d8da5e4bba64a751350491b3ccaf\", \"jingle_unittests\": \"62c764ff02c9465ced12414303cb728ba2abf0a1\", \"latency_unittests\": \"0e3f99af361be3733d294878f63aee5cf8d3eba2\", \"libjingle_xmpp_unittests\": \"8017221b78009953fbae8f1b2a01302dc3a23176\", \"liburlpattern_unittests\": \"75ba19a0dea304bd6eb561e6f0a96c023b8b2cce\", \"media_blink_unittests\": \"d5d1c82e1bf48ee23d6092e2c7ad7ff9a18d8cc3\", \"media_unittests\": \"96698729efffe78a2841179950fb3cc72fdbf15c\", \"message_center_unittests\": \"1ea1c38fc9fb78e79270ac0c6361c39411e4663a\", \"midi_unittests\": \"f3b308cd75a8b85fac5f83f0fbc4ada6d7883933\", \"mojo_core_unittests\": \"fa9f3e30eb7f093b0e37f82e20b063d2c535648a\", \"mojo_unittests\": \"fde170d88ec137a08977e91b0d75504ab5533818\", \"nacl_loader_unittests\": \"1a568e5c9f03e352f9bc924863d91033fac576cf\", \"native_theme_unittests\": \"872e30f69031707cc83af6230063b6b99fdd1589\", \"net_unittests\": \"5baf97e060f6c039861c7fe2ec1f01b8f203a99c\", \"notification_helper_unittests\": \"d63043ce74a5c23b4cd25c18ab199a781017db30\", \"pdf_unittests\": \"5fd7092564cb6d5e9f2a3820538977abc9a46ea0\", \"perfetto_unittests\": \"d742e30d9bd4c65c866853c91ac11c62feb159f3\", \"ppapi_unittests\": \"095ef7f077670dff3abbd4f12f501b162e165b4a\", \"printing_unittests\": \"9fb12fccee7af9b4014b3c59293713a24c996bab\", \"remoting_unittests\": \"a50382ee27c21c01fa417b1153c43926485b89e0\", \"sbox_integration_tests\": \"af187132fcc3e20595faa3ffd11695ff956baa5a\", \"sbox_unittests\": \"fdc8c01dae39594e6d62e1ebeff3ca88215207e0\", \"sbox_validation_tests\": \"c68bb41cf5722446eaf3229b3283b967ae6e7a82\", \"service_manager_unittests\": \"c8bb8f212b041ef0ccba819d173897faab0073e4\", \"services_unittests\": \"d076a9518f87f6dc53d6cf10da6e07c959f89b97\", \"setup_unittests\": \"b96240b99cb62a658b3dee808ad862c487918cd6\", \"shell_dialogs_unittests\": \"23a7d71d88006bb8dfd2378c7500f388f70f41e8\", \"skia_unittests\": \"ce17b75f6ef9e58b57f22ef39dae808bda956efb\", \"snapshot_unittests\": \"a74eef2f92f212258188a50a10394b2cc8b728fb\", \"sql_unittests\": \"574b2fae6b73b55f822c7ba09b50ee3cf35565db\", \"storage_unittests\": \"7c6c5a0c2629fa23a87bc6a89d280cb52e47ef96\", \"sync_integration_tests\": \"221211dd030f6b2ff7501aeeb6038fff7f12d73f\", \"traffic_annotation_auditor_unittests\": \"b17b353112748e4e6f24fe8631b52dffeb90082b\", \"ui_base_unittests\": \"b4765b1d7e9e14ea871e44712a33aceee74e0d7a\", \"ui_touch_selection_unittests\": \"6b8481c843ef9883d956b968613c663760f33ef0\", \"unit_tests\": \"89c4baa6303424bc0e346efa2dff8369331b02f8\", \"updater_tests\": \"b59835c723d1dd2fbc227e6bd0d155565e55ed2a\", \"url_unittests\": \"3cf39c8679f37c7a057460d382d26ee9a28b17a1\", \"views_unittests\": \"9054f128fb1f92eef0bfa7cacdcc176da590b8da\", \"viz_unittests\": \"902cb0793f012279666baf853e517054bc2dbd58\", \"vr_common_unittests\": \"ad3e0658d6be158924b0f7226988a0d53f0b2bdf\", \"vr_pixeltests\": \"546fe94362491d7cd919659336a7a017153e7b98\", \"weblayer_browsertests\": \"b5f6001555c1c8b7de5c59ec73af80e93ebd7402\", \"weblayer_unittests\": \"1960a57f229dfdec4e8b930fb5f2d0ab8620dccc\", \"wm_unittests\": \"80c67bce37f3812d80b133428063e234f6ab9422\", \"wtf_unittests\": \"c89d7f915647265f0b8d21c4cc60a3f615503a1a\", \"zlib_unittests\": \"d327ec5ddfe03de3724d7af6cbca38cb5bcad0de\", \"zucchini_unittests\": \"5b4a35d76a5156d73f0269d42d294a2eb211fcfd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-1-ervs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5661-c6f6250\"], \"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.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610444779698883",
"status": "COMPLETED",
"status_changed_ts": "1610444779988088",
"tags": [
"build_address:luci.chromium.ci/win-asan/16774",
"builder:win-asan",
"buildset:commit/git/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2c2fdc7c8a8030cc97012e30164f27653574c1a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023750036266585360",
"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/8858319974171399328/+/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:510eceaeb481b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1610448528086954",
"url": "https://ci.chromium.org/b/8858319974171399328",
"utcnow_ts": "1610448528752609"
}
}
[10:48:48.762] Invocation finished in 1h2m33.811522965s with status FAILED