[18:15:32.492] New invocation is queued and will start shortly
[18:15:33.772] Starting the invocation (attempt 1)
[18:15:33.812] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:15:33.812] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:15:33.812] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021724861119069424",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"74d18195fd814ce0f5be3591d27c1a6850cded07\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021724861119069424",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/74d18195fd814ce0f5be3591d27c1a6850cded07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74d18195fd814ce0f5be3591d27c1a6850cded07",
"gitiles_ref:refs/heads/master"
]
}
[18:15:34.587] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612376133904924",
"id": "8856294798973335680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"74d18195fd814ce0f5be3591d27c1a6850cded07\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612376134442873",
"tags": [
"build_address:luci.chromium.ci/win-asan/17152",
"builder:win-asan",
"buildset:commit/git/74d18195fd814ce0f5be3591d27c1a6850cded07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74d18195fd814ce0f5be3591d27c1a6850cded07",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021724861119069424",
"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/8856294798973335680/+/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": "1612376134443024",
"url": "https://ci.chromium.org/b/8856294798973335680",
"utcnow_ts": "1612376134577419"
}
}
[18:15:34.587] Task URL: https://ci.chromium.org/b/8856294798973335680
[18:15:34.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:2:0) after 1m0s
[18:15:41.094] Received PubSub notification, asking Buildbucket for the build status
[18:15:41.196] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:34.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:2:0)
[18:16:34.688] Timer tick, asking Buildbucket for the build status
[18:16:34.775] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:34.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:4:0) after 1m0s
[18:17:34.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:4:0)
[18:17:34.801] Timer tick, asking Buildbucket for the build status
[18:17:35.072] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:35.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:5:0) after 1m0s
[18:18:35.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:5:0)
[18:18:35.105] Timer tick, asking Buildbucket for the build status
[18:18:35.212] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:35.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:6:0) after 1m0s
[18:19:35.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:6:0)
[18:19:35.247] Timer tick, asking Buildbucket for the build status
[18:19:35.345] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:35.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:7:0) after 1m0s
[18:20:35.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:7:0)
[18:20:35.644] Timer tick, asking Buildbucket for the build status
[18:20:35.932] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:35.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:8:0) after 1m0s
[18:21:35.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:8:0)
[18:21:35.979] Timer tick, asking Buildbucket for the build status
[18:21:36.113] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:36.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:9:0) after 1m0s
[18:22:36.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:9:0)
[18:22:36.101] Timer tick, asking Buildbucket for the build status
[18:22:36.252] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:36.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:10:0) after 1m0s
[18:23:36.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:10:0)
[18:23:36.284] Timer tick, asking Buildbucket for the build status
[18:23:36.534] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:36.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:11:0) after 1m0s
[18:24:36.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:11:0)
[18:24:36.761] Timer tick, asking Buildbucket for the build status
[18:24:37.119] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:37.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:12:0) after 1m0s
[18:25:37.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:12:0)
[18:25:37.268] Timer tick, asking Buildbucket for the build status
[18:25:37.489] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:37.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:13:0) after 1m0s
[18:26:37.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:13:0)
[18:26:37.596] Timer tick, asking Buildbucket for the build status
[18:26:37.674] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:37.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:14:0) after 1m0s
[18:27:37.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:14:0)
[18:27:37.692] Timer tick, asking Buildbucket for the build status
[18:27:37.795] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:37.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:15:0) after 1m0s
[18:28:37.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:15:0)
[18:28:37.861] Timer tick, asking Buildbucket for the build status
[18:28:37.944] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:37.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:16:0) after 1m0s
[18:29:37.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:16:0)
[18:29:37.970] Timer tick, asking Buildbucket for the build status
[18:29:38.042] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:38.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:17:0) after 1m0s
[18:30:38.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:17:0)
[18:30:38.085] Timer tick, asking Buildbucket for the build status
[18:30:38.199] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:38.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:18:0) after 1m0s
[18:31:38.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:18:0)
[18:31:38.337] Timer tick, asking Buildbucket for the build status
[18:31:38.396] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:38.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:19:0) after 1m0s
[18:32:38.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:19:0)
[18:32:38.512] Timer tick, asking Buildbucket for the build status
[18:32:38.566] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:38.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:20:0) after 1m0s
[18:33:38.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:20:0)
[18:33:38.895] Timer tick, asking Buildbucket for the build status
[18:33:39.055] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:39.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:21:0) after 1m0s
[18:34:39.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:21:0)
[18:34:39.224] Timer tick, asking Buildbucket for the build status
[18:34:39.364] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:39.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:22:0) after 1m0s
[18:35:39.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:22:0)
[18:35:39.449] Timer tick, asking Buildbucket for the build status
[18:35:40.051] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:40.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:23:0) after 1m0s
[18:36:40.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:23:0)
[18:36:40.178] Timer tick, asking Buildbucket for the build status
[18:36:43.234] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:43.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:24:0) after 1m0s
[18:37:43.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:24:0)
[18:37:43.265] Timer tick, asking Buildbucket for the build status
[18:37:43.394] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:43.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:25:0) after 1m0s
[18:38:43.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:25:0)
[18:38:43.491] Timer tick, asking Buildbucket for the build status
[18:38:43.620] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:43.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:26:0) after 1m0s
[18:39:43.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:26:0)
[18:39:43.680] Timer tick, asking Buildbucket for the build status
[18:39:43.906] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:43.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:27:0) after 1m0s
[18:40:43.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:27:0)
[18:40:43.964] Timer tick, asking Buildbucket for the build status
[18:40:44.087] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:44.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:28:0) after 1m0s
[18:41:44.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:28:0)
[18:41:44.126] Timer tick, asking Buildbucket for the build status
[18:41:45.168] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:45.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:29:0) after 1m0s
[18:42:45.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:29:0)
[18:42:45.203] Timer tick, asking Buildbucket for the build status
[18:42:45.356] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:45.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:30:0) after 1m0s
[18:43:45.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:30:0)
[18:43:45.411] Timer tick, asking Buildbucket for the build status
[18:43:45.527] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:45.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:31:0) after 1m0s
[18:44:45.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:31:0)
[18:44:45.558] Timer tick, asking Buildbucket for the build status
[18:44:45.702] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:45.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:32:0) after 1m0s
[18:45:45.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:32:0)
[18:45:45.767] Timer tick, asking Buildbucket for the build status
[18:45:45.898] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:45.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:33:0) after 1m0s
[18:46:45.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:33:0)
[18:46:45.937] Timer tick, asking Buildbucket for the build status
[18:46:46.100] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:46.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:34:0) after 1m0s
[18:47:46.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:34:0)
[18:47:46.187] Timer tick, asking Buildbucket for the build status
[18:47:46.295] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:46.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:35:0) after 1m0s
[18:48:46.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:35:0)
[18:48:46.599] Timer tick, asking Buildbucket for the build status
[18:48:46.712] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:46.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:36:0) after 1m0s
[18:49:46.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:36:0)
[18:49:46.995] Timer tick, asking Buildbucket for the build status
[18:49:47.155] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:47.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:37:0) after 1m0s
[18:50:47.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:37:0)
[18:50:47.495] Timer tick, asking Buildbucket for the build status
[18:50:47.601] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:47.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:38:0) after 1m0s
[18:51:48.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:38:0)
[18:51:48.124] Timer tick, asking Buildbucket for the build status
[18:51:48.285] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:48.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:39:0) after 1m0s
[18:52:48.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:39:0)
[18:52:48.601] Timer tick, asking Buildbucket for the build status
[18:52:48.719] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:48.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:40:0) after 1m0s
[18:53:48.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:40:0)
[18:53:48.865] Timer tick, asking Buildbucket for the build status
[18:53:49.350] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:49.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:41:0) after 1m0s
[18:54:49.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:41:0)
[18:54:49.480] Timer tick, asking Buildbucket for the build status
[18:54:50.246] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:50.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:42:0) after 1m0s
[18:55:50.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:42:0)
[18:55:50.409] Timer tick, asking Buildbucket for the build status
[18:55:50.641] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:50.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:43:0) after 1m0s
[18:56:50.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:43:0)
[18:56:50.761] Timer tick, asking Buildbucket for the build status
[18:56:50.998] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:50.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:44:0) after 1m0s
[18:57:51.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:44:0)
[18:57:51.031] Timer tick, asking Buildbucket for the build status
[18:57:51.339] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:51.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:45:0) after 1m0s
[18:58:51.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:45:0)
[18:58:51.443] Timer tick, asking Buildbucket for the build status
[18:58:51.640] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:51.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:46:0) after 1m0s
[18:59:51.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:46:0)
[18:59:51.733] Timer tick, asking Buildbucket for the build status
[18:59:51.839] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:51.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:47:0) after 1m0s
[19:00:51.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:47:0)
[19:00:51.870] Timer tick, asking Buildbucket for the build status
[19:00:51.996] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:51.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:48:0) after 1m0s
[19:01:52.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:48:0)
[19:01:52.025] Timer tick, asking Buildbucket for the build status
[19:01:52.451] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:52.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:49:0) after 1m0s
[19:02:52.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:49:0)
[19:02:52.676] Timer tick, asking Buildbucket for the build status
[19:02:52.835] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:52.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:50:0) after 1m0s
[19:03:52.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:50:0)
[19:03:52.890] Timer tick, asking Buildbucket for the build status
[19:03:53.030] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:53.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:51:0) after 1m0s
[19:04:53.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:51:0)
[19:04:53.052] Timer tick, asking Buildbucket for the build status
[19:04:53.208] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:53.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:52:0) after 1m0s
[19:05:53.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:52:0)
[19:05:53.305] Timer tick, asking Buildbucket for the build status
[19:05:53.499] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:53.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:53:0) after 1m0s
[19:06:53.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:53:0)
[19:06:53.612] Timer tick, asking Buildbucket for the build status
[19:06:53.681] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:53.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:54:0) after 1m0s
[19:07:53.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:54:0)
[19:07:53.686] Timer tick, asking Buildbucket for the build status
[19:07:53.846] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:53.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:55:0) after 1m0s
[19:08:53.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:55:0)
[19:08:53.890] Timer tick, asking Buildbucket for the build status
[19:08:53.990] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:53.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:56:0) after 1m0s
[19:09:54.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:56:0)
[19:09:54.027] Timer tick, asking Buildbucket for the build status
[19:09:54.167] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:54.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:57:0) after 1m0s
[19:10:54.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:57:0)
[19:10:54.157] Timer tick, asking Buildbucket for the build status
[19:10:54.335] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:58:0) after 1m0s
[19:11:54.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:58:0)
[19:11:54.327] Timer tick, asking Buildbucket for the build status
[19:11:54.497] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:59:0) after 1m0s
[19:12:54.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:59:0)
[19:12:54.526] Timer tick, asking Buildbucket for the build status
[19:12:54.656] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:54.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:60:0) after 1m0s
[19:13:54.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:60:0)
[19:13:54.719] Timer tick, asking Buildbucket for the build status
[19:13:54.798] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:54.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:61:0) after 1m0s
[19:14:54.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:61:0)
[19:14:54.874] Timer tick, asking Buildbucket for the build status
[19:14:54.962] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:54.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:62:0) after 1m0s
[19:15:55.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:62:0)
[19:15:55.076] Timer tick, asking Buildbucket for the build status
[19:15:55.272] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:55.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:63:0) after 1m0s
[19:16:55.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:63:0)
[19:16:55.312] Timer tick, asking Buildbucket for the build status
[19:16:55.386] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:55.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:64:0) after 1m0s
[19:17:55.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:64:0)
[19:17:55.460] Timer tick, asking Buildbucket for the build status
[19:17:55.603] Build 8856294798973335680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:55.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021724861119069424:65:0) after 1m0s
[19:18:03.556] Received PubSub notification, asking Buildbucket for the build status
[19:18:04.137] Build 8856294798973335680: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:18:04.137] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612379882724799",
"created_by": "project:chromium",
"created_ts": "1612376133904924",
"failure_reason": "BUILD_FAILURE",
"id": "8856294798973335680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"74d18195fd814ce0f5be3591d27c1a6850cded07\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithBadModelInfoFileShouldDeleteTempFile\"}, \"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\": \"74d18195fd814ce0f5be3591d27c1a6850cded07\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17152, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"2e54a1e7fc49e1c1c659d7be659b832954112106\", \"got_dawn_revision\": \"f2a003d4e94cbb529d638e9a1b8a551c9f3371ab\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"74d18195fd814ce0f5be3591d27c1a6850cded07\", \"got_revision_cp\": \"refs/heads/master@{#850189}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"74d18195fd814ce0f5be3591d27c1a6850cded07\", \"swarm_hashes_refs/heads/master(at){#850189}_without_patch\": {\"absl_hardening_tests\": \"a9291af7f06ebe2570cfbde4b925818b252b3d56\", \"accessibility_unittests\": \"4fab4c28116466de01de0a140fbbb4222f5af30f\", \"angle_unittests\": \"d4cac7a90e56d1aae1bfecff7beb6c07cfa1dfaa\", \"app_shell_unittests\": \"e9fe548c05b44145d51f58f90d389941f754af56\", \"aura_unittests\": \"4e4786dc8d9a5fd05ea80925bd6bfa1a9c08d97c\", \"base_unittests\": \"d343412b8fe53575e02e975296bb85c7978c1835\", \"base_util_unittests\": \"217c72f75074e0a0869dbcd7e9978b6ca663b9f9\", \"blink_common_unittests\": \"61531adfb6881c37caa803184dddfb5660d636a9\", \"blink_fuzzer_unittests\": \"f1a8e4f67bb2a53f418a648c060c3541d2dd9e65\", \"blink_heap_unittests\": \"4523acdf9e5ee49bf5410a0db909170130030d66\", \"blink_platform_unittests\": \"8ff5d65407d2adcf3c49a7d727ee8a369d37add8\", \"blink_unittests\": \"619106f4fbc85445a51f6640a7e528620e20c79d\", \"boringssl_crypto_tests\": \"cc970a9cc9ea9a158c6758d29c0af5fe3fb63b97\", \"boringssl_ssl_tests\": \"b753177e279943e9dc662d68b0781b961f5b1a27\", \"browser_switcher_bho_unittests\": \"39bc35cbe5d9883c8cd7e448e0a4e8ed2e959ec5\", \"browser_tests\": \"652b0762a93b6bff43fade4f81b53601732412e9\", \"capture_unittests\": \"15b967033ae1db5faf342f1f20c245f95e77c0e5\", \"cast_unittests\": \"63a819122a45fa6768156edaa53e796f27dcbdb0\", \"cc_unittests\": \"cc4d03dba7a44dd09e04b4f3098491907d12c521\", \"chrome_app_unittests\": \"3e41f579258599354ad37b140fdf83430e8dd066\", \"chrome_cleaner_unittests\": \"bd5964477e332ae86f4fa2706a52fc38e608f1ba\", \"chrome_elf_unittests\": \"90804e4bd0b04dea372c91571cff67e97c50dd2c\", \"chromedriver_unittests\": \"75993c521d94719063f01b01b4b78909c467b0a3\", \"components_browsertests\": \"9f32e467e4425d0dc8380f30709697823b40ac62\", \"components_unittests\": \"77fe1ab5983df5424ffdf485030c1b218c7bb7ef\", \"compositor_unittests\": \"57764f3c4c11eeaf241b5783c190a0b0cdc30992\", \"content_browsertests\": \"4fe4e50d978980b78f238affa5426f3311ad5c19\", \"content_unittests\": \"80a1bcffe0cb8c3ac10c720a63815e8b0a09160e\", \"courgette_unittests\": \"2c6c1562dbf0bc05600f1d127443ad31bf303534\", \"crashpad_tests\": \"0dd2b06a0fc33175ace6f95121c468288dc0912c\", \"cronet_tests\": \"49b4fa268131f2f2a248bf416d0c4b0e536410c4\", \"cronet_unittests\": \"1bf5d716a9bd97952e73a9620e8ed3a8794b78be\", \"crypto_unittests\": \"5ac9817692cb0138cd9c62ece0cea9a7ad81e315\", \"delayloads_unittests\": \"8e1200a5d6a73cd8aa95130a7d762e40efee96ff\", \"device_unittests\": \"4da084a9719872b0599362d8a5872f9071ea1918\", \"display_unittests\": \"fbabd3b2f9295f4d2eb57450409c269387b0baab\", \"elevation_service_unittests\": \"0827cba7331b11b44d90a69345966b67670206aa\", \"events_unittests\": \"cd47e24d12252d0e1f6c36c6fde2053d1a2026a6\", \"extensions_browsertests\": \"fc0ec9e0f9bcb12d4ff08fcf9e12233c95183296\", \"extensions_unittests\": \"5582075fb093038e6553a343e51e80c3991fdb01\", \"filesystem_service_unittests\": \"7b686212c340f45ba5980d5778e2096b55bdf8bf\", \"gcm_unit_tests\": \"12259cda83a511732bab8508c3b5fa06ad307375\", \"gcp_unittests\": \"fc64394bcd7d219f6ee1a9165a21fbc6e01dba83\", \"gfx_unittests\": \"25ae466b9c4c99617eeffbc3301cacc3aa1216ba\", \"gin_unittests\": \"4303a23a8135044110295bf0cd28268d8e844cd8\", \"google_apis_unittests\": \"be654be75053ecf5d7c992eda9048edba06067d1\", \"gpu_unittests\": \"49d24ab036ca4074563bb1d56bd9aa3ee2b03499\", \"gwp_asan_unittests\": \"e0a465e023188534903d57700a772165a0f39b9e\", \"headless_browsertests\": \"5044ddbcd07810b803dfd0cce2d46f4641cef007\", \"headless_unittests\": \"a06443900873715ff8b6c9ba23d8e2e9335b3384\", \"install_static_unittests\": \"c561b175102ad9e10373ea67c7204775cf5401d7\", \"installer_util_unittests\": \"80458ec177a8e2a3022d9f0d0cf876fccce055db\", \"interactive_ui_tests\": \"85da9949cd16dce9dfadced5446efbb87232b803\", \"ipc_tests\": \"2c9d74c4b3163889cef292eb1c0dc6887a8009a3\", \"jingle_unittests\": \"76859d24f14e005b28f26d389d3e067ca3c9e05a\", \"latency_unittests\": \"dc2757a8f99a883db705e53739d91e602bb4ebfb\", \"libjingle_xmpp_unittests\": \"cd75a121c77d3e34991c91f496ce62ff3ec8019e\", \"liburlpattern_unittests\": \"5b1c66e2b137bdc6f6d74e21b52dce5da007d6b6\", \"media_blink_unittests\": \"e25bcd720c6cf9e00d159cb2a02f7d2a2c978fde\", \"media_unittests\": \"94a0ad5731720a077ff1d3f10f1a590590b05c48\", \"message_center_unittests\": \"4d81b3c7b94fb27ae1f1cbc82d4a4db18d537838\", \"midi_unittests\": \"4e1dadec7758b5d5dbd8392f09466c3b926a25a8\", \"mojo_core_unittests\": \"0664075ec1dc3545be9d3b2e55834851c65eb09f\", \"mojo_unittests\": \"ffa7bcb5d25aebb2e4309ddcf2de4aa9d785324c\", \"nacl_loader_unittests\": \"0cb7e9b48cd8bb8a5018c52d3808b5237321fa5a\", \"native_theme_unittests\": \"8cc8b99c4842c0517d3fd385affa78a7236774b7\", \"net_unittests\": \"ffc640a01f2c5410b14cfca46f8e7932fbde4f84\", \"notification_helper_unittests\": \"37035e83284bb39c43881143d265961db2a39629\", \"pdf_unittests\": \"9e4dae47f95d6a00df52e8979623854be1062fa9\", \"perfetto_unittests\": \"1131d3ed0b96d3329530a127d4b8bf2e4bbcfa99\", \"ppapi_unittests\": \"ffe85fd548c5ee39dca988516d938a44a197ef4d\", \"printing_unittests\": \"aa6455162ae3fdc66c6d14e47c052f5f9d77d3da\", \"remoting_unittests\": \"15c487bede7a7a7236b59a61a00a76634d270aa7\", \"sbox_integration_tests\": \"9519bc2cf1fe967e915691b092129297134a0d5f\", \"sbox_unittests\": \"7467014ba77c9429f77f120eec15326da85ff86c\", \"sbox_validation_tests\": \"885ada91dd19caceb79ac2f9e33d27409bb5c425\", \"service_manager_unittests\": \"fb6191791b59a3417cfc93f34e077b7768e30ffc\", \"services_unittests\": \"daafe3cfbde35a2318f3a3aaad772c654da24759\", \"setup_unittests\": \"e4d52ca74510cf30506631f5bac265ccdb4c744d\", \"shell_dialogs_unittests\": \"f8370e910cb52b5561a7a288cd7aa177f118e078\", \"skia_unittests\": \"c26954838be893893313e392773187bc17722e50\", \"snapshot_unittests\": \"e1aacb05531b7d3dc2d2622fb5669fbd9f25a012\", \"sql_unittests\": \"db89a53d95b6c76ff377d65a797173c0ffd52e46\", \"storage_unittests\": \"de8f2a7e3f75620829e259ae34dfb92c1bda85a7\", \"sync_integration_tests\": \"e63645e979aa5f399fe87b61de93bab7dfb1e926\", \"traffic_annotation_auditor_unittests\": \"86499c5da4a1c895c63613af953ccce64baad19d\", \"ui_base_unittests\": \"e1fe15efdeacca1e6b71322153859b7e4c21cd36\", \"ui_touch_selection_unittests\": \"f0da0b43a37f76c872c01b39ce7e8c3f93c75eff\", \"unit_tests\": \"9460b87120e2d7399c15f8eabb376f91f893c16f\", \"updater_tests\": \"c57c6bfb9c9908bc2367c6335dea8cc9b7e93c7c\", \"url_unittests\": \"16cbe2c1a827bfd52f02a1596d6f5a1bac03ef78\", \"views_unittests\": \"094bd9c26ca76847cd53044c19324a9fe9ae6b5e\", \"viz_unittests\": \"e523a018b666f5d7aed18487b6468d274c2b747b\", \"vr_common_unittests\": \"fa3285b4880fc574953310d3978e0b7588bd8a60\", \"vr_pixeltests\": \"c2fb96b6bdd8fa4dcdc640122237dec46d6ffb73\", \"weblayer_browsertests\": \"70c92fdbc1e1094031faba32e79c5f7899781a54\", \"weblayer_unittests\": \"38a2bb03be0a7e63ba7191dceaf30a703d3821a1\", \"wm_unittests\": \"d851b8e41cf74c8f68eed9102ac01328836f0899\", \"wtf_unittests\": \"15da6ed88e808fec6a1b1fc4ca2a2c5eb38d8c10\", \"zlib_unittests\": \"58c1ac4df3d1873b5c3a42c49631278240a88867\", \"zucchini_unittests\": \"7919a2fcef8ad09440c9238e6855e4fb316ab453\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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.VerifiedCrxWithBadModelInfoFileShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612376139348156",
"status": "COMPLETED",
"status_changed_ts": "1612376140347147",
"tags": [
"build_address:luci.chromium.ci/win-asan/17152",
"builder:win-asan",
"buildset:commit/git/74d18195fd814ce0f5be3591d27c1a6850cded07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/74d18195fd814ce0f5be3591d27c1a6850cded07",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021724861119069424",
"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/8856294798973335680/+/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:5181ecdc8f935d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612379883221236",
"url": "https://ci.chromium.org/b/8856294798973335680",
"utcnow_ts": "1612379884118884"
}
}
[19:18:04.137] Invocation finished in 1h2m31.662274987s with status FAILED