[09:02:32.918] New invocation is queued and will start shortly
[09:02:34.007] Starting the invocation (attempt 1)
[09:02:34.079] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:02:34.080] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:02:34.080] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025564725013248896",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2a842e6c83fd9601554537501c6c3a59d3bf836e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9025564725013248896",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"gitiles_ref:refs/heads/master"
]
}
[09:02:35.299] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608714154256492",
"id": "8860134662945593536",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2a842e6c83fd9601554537501c6c3a59d3bf836e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608714154819734",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/6860",
"builder:android-bfcache-rel",
"buildset:commit/git/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025564725013248896",
"scheduler_job_id:chromium/android-bfcache-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860134662945593536/+/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": "1608714154819883",
"url": "https://ci.chromium.org/b/8860134662945593536",
"utcnow_ts": "1608714155287397"
}
}
[09:02:35.299] Task URL: https://ci.chromium.org/b/8860134662945593536
[09:02:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:2:0) after 1m0s
[09:03:35.331] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:2:0)
[09:03:35.331] Timer tick, asking Buildbucket for the build status
[09:03:35.425] Build 8860134662945593536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:35.425] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:3:0) after 1m0s
[09:03:53.686] Received PubSub notification, asking Buildbucket for the build status
[09:03:53.768] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:35.491] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:3:0)
[09:04:35.491] Timer tick, asking Buildbucket for the build status
[09:04:35.825] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:35.825] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:5:0) after 1m0s
[09:05:35.880] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:5:0)
[09:05:35.880] Timer tick, asking Buildbucket for the build status
[09:05:36.319] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:36.319] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:6:0) after 1m0s
[09:06:36.345] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:6:0)
[09:06:36.345] Timer tick, asking Buildbucket for the build status
[09:06:36.493] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:36.493] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:7:0) after 1m0s
[09:07:36.515] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:7:0)
[09:07:36.516] Timer tick, asking Buildbucket for the build status
[09:07:36.622] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:36.622] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:8:0) after 1m0s
[09:08:36.670] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:8:0)
[09:08:36.670] Timer tick, asking Buildbucket for the build status
[09:08:36.795] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:36.795] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:9:0) after 1m0s
[09:09:36.870] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:9:0)
[09:09:36.870] Timer tick, asking Buildbucket for the build status
[09:09:37.014] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:37.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:10:0) after 1m0s
[09:10:37.047] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:10:0)
[09:10:37.047] Timer tick, asking Buildbucket for the build status
[09:10:37.162] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:37.162] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:11:0) after 1m0s
[09:11:37.212] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:11:0)
[09:11:37.212] Timer tick, asking Buildbucket for the build status
[09:11:37.274] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:37.274] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:12:0) after 1m0s
[09:12:37.301] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:12:0)
[09:12:37.301] Timer tick, asking Buildbucket for the build status
[09:12:37.412] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:37.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:13:0) after 1m0s
[09:13:37.425] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:13:0)
[09:13:37.425] Timer tick, asking Buildbucket for the build status
[09:13:37.490] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:37.490] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:14:0) after 1m0s
[09:14:37.545] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:14:0)
[09:14:37.545] Timer tick, asking Buildbucket for the build status
[09:14:37.926] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:37.926] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:15:0) after 1m0s
[09:15:37.957] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:15:0)
[09:15:37.957] Timer tick, asking Buildbucket for the build status
[09:15:38.117] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:38.117] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:16:0) after 1m0s
[09:16:38.151] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:16:0)
[09:16:38.151] Timer tick, asking Buildbucket for the build status
[09:16:38.217] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:38.217] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:17:0) after 1m0s
[09:17:38.237] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:17:0)
[09:17:38.237] Timer tick, asking Buildbucket for the build status
[09:17:38.364] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:38.364] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:18:0) after 1m0s
[09:18:38.395] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:18:0)
[09:18:38.395] Timer tick, asking Buildbucket for the build status
[09:18:38.636] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:38.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:19:0) after 1m0s
[09:19:38.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:19:0)
[09:19:38.716] Timer tick, asking Buildbucket for the build status
[09:19:39.798] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:39.798] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:20:0) after 1m0s
[09:20:39.833] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:20:0)
[09:20:39.833] Timer tick, asking Buildbucket for the build status
[09:20:39.923] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:39.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:21:0) after 1m0s
[09:21:39.909] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:21:0)
[09:21:39.909] Timer tick, asking Buildbucket for the build status
[09:21:40.053] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:40.053] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:22:0) after 1m0s
[09:22:40.055] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:22:0)
[09:22:40.055] Timer tick, asking Buildbucket for the build status
[09:22:40.155] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:40.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:23:0) after 1m0s
[09:23:40.178] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:23:0)
[09:23:40.178] Timer tick, asking Buildbucket for the build status
[09:23:40.275] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:40.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:24:0) after 1m0s
[09:24:40.299] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:24:0)
[09:24:40.299] Timer tick, asking Buildbucket for the build status
[09:24:40.374] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:40.374] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:25:0) after 1m0s
[09:25:40.398] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:25:0)
[09:25:40.398] Timer tick, asking Buildbucket for the build status
[09:25:40.497] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:40.497] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:26:0) after 1m0s
[09:26:40.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:26:0)
[09:26:40.519] Timer tick, asking Buildbucket for the build status
[09:26:40.624] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:40.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:27:0) after 1m0s
[09:27:40.652] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:27:0)
[09:27:40.652] Timer tick, asking Buildbucket for the build status
[09:27:40.802] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:40.803] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:28:0) after 1m0s
[09:28:40.857] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:28:0)
[09:28:40.857] Timer tick, asking Buildbucket for the build status
[09:28:40.961] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:40.961] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:29:0) after 1m0s
[09:29:40.997] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:29:0)
[09:29:40.997] Timer tick, asking Buildbucket for the build status
[09:29:41.433] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:41.433] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:30:0) after 1m0s
[09:30:41.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:30:0)
[09:30:41.477] Timer tick, asking Buildbucket for the build status
[09:30:41.923] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:41.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:31:0) after 1m0s
[09:31:41.963] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:31:0)
[09:31:41.963] Timer tick, asking Buildbucket for the build status
[09:31:42.161] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:42.161] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:32:0) after 1m0s
[09:32:42.214] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:32:0)
[09:32:42.214] Timer tick, asking Buildbucket for the build status
[09:32:42.356] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:42.356] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:33:0) after 1m0s
[09:33:42.414] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:33:0)
[09:33:42.414] Timer tick, asking Buildbucket for the build status
[09:33:42.492] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:42.492] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:34:0) after 1m0s
[09:34:42.531] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:34:0)
[09:34:42.531] Timer tick, asking Buildbucket for the build status
[09:34:42.595] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:42.595] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:35:0) after 1m0s
[09:35:42.623] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:35:0)
[09:35:42.623] Timer tick, asking Buildbucket for the build status
[09:35:42.713] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:42.713] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:36:0) after 1m0s
[09:36:42.779] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:36:0)
[09:36:42.779] Timer tick, asking Buildbucket for the build status
[09:36:43.063] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:43.063] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:37:0) after 1m0s
[09:37:43.119] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:37:0)
[09:37:43.119] Timer tick, asking Buildbucket for the build status
[09:37:43.231] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:43.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:38:0) after 1m0s
[09:38:43.218] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:38:0)
[09:38:43.218] Timer tick, asking Buildbucket for the build status
[09:38:44.300] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:44.300] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:39:0) after 1m0s
[09:39:44.322] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:39:0)
[09:39:44.322] Timer tick, asking Buildbucket for the build status
[09:39:44.407] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:40:0) after 1m0s
[09:40:44.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:40:0)
[09:40:44.428] Timer tick, asking Buildbucket for the build status
[09:40:44.654] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:44.654] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:41:0) after 1m0s
[09:41:44.712] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:41:0)
[09:41:44.712] Timer tick, asking Buildbucket for the build status
[09:41:45.534] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:45.534] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:42:0) after 1m0s
[09:42:45.556] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:42:0)
[09:42:45.556] Timer tick, asking Buildbucket for the build status
[09:42:45.694] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:45.694] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:43:0) after 1m0s
[09:43:45.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:43:0)
[09:43:45.716] Timer tick, asking Buildbucket for the build status
[09:43:45.771] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:45.771] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:44:0) after 1m0s
[09:44:45.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:44:0)
[09:44:45.800] Timer tick, asking Buildbucket for the build status
[09:44:45.861] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:45.861] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:45:0) after 1m0s
[09:45:45.955] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:45:0)
[09:45:45.955] Timer tick, asking Buildbucket for the build status
[09:45:46.014] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:46.015] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:46:0) after 1m0s
[09:46:46.067] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:46:0)
[09:46:46.067] Timer tick, asking Buildbucket for the build status
[09:46:46.322] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:46.322] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:47:0) after 1m0s
[09:47:46.367] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:47:0)
[09:47:46.367] Timer tick, asking Buildbucket for the build status
[09:47:46.498] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:46.498] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:48:0) after 1m0s
[09:48:46.526] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:48:0)
[09:48:46.526] Timer tick, asking Buildbucket for the build status
[09:48:46.599] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:46.599] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:49:0) after 1m0s
[09:49:46.633] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:49:0)
[09:49:46.633] Timer tick, asking Buildbucket for the build status
[09:49:46.694] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:46.694] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:50:0) after 1m0s
[09:50:46.725] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:50:0)
[09:50:46.726] Timer tick, asking Buildbucket for the build status
[09:50:46.781] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:46.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:51:0) after 1m0s
[09:51:46.762] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:51:0)
[09:51:46.762] Timer tick, asking Buildbucket for the build status
[09:51:46.825] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:46.825] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:52:0) after 1m0s
[09:52:46.847] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:52:0)
[09:52:46.847] Timer tick, asking Buildbucket for the build status
[09:52:46.936] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:46.936] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:53:0) after 1m0s
[09:53:46.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:53:0)
[09:53:46.942] Timer tick, asking Buildbucket for the build status
[09:53:47.001] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:47.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:54:0) after 1m0s
[09:54:47.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:54:0)
[09:54:47.079] Timer tick, asking Buildbucket for the build status
[09:54:47.365] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:47.365] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:55:0) after 1m0s
[09:55:47.459] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:55:0)
[09:55:47.459] Timer tick, asking Buildbucket for the build status
[09:55:47.612] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:47.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:56:0) after 1m0s
[09:56:47.642] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:56:0)
[09:56:47.642] Timer tick, asking Buildbucket for the build status
[09:56:47.726] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:47.726] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:57:0) after 1m0s
[09:57:47.760] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:57:0)
[09:57:47.760] Timer tick, asking Buildbucket for the build status
[09:57:49.299] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:49.299] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:58:0) after 1m0s
[09:58:49.345] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:58:0)
[09:58:49.345] Timer tick, asking Buildbucket for the build status
[09:58:49.474] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:49.474] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:59:0) after 1m0s
[09:59:49.525] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:59:0)
[09:59:49.525] Timer tick, asking Buildbucket for the build status
[09:59:49.692] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:49.692] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:60:0) after 1m0s
[10:00:49.707] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:60:0)
[10:00:49.707] Timer tick, asking Buildbucket for the build status
[10:00:49.768] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:61:0) after 1m0s
[10:01:49.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:61:0)
[10:01:49.800] Timer tick, asking Buildbucket for the build status
[10:01:49.914] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:49.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:62:0) after 1m0s
[10:02:49.950] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:62:0)
[10:02:49.950] Timer tick, asking Buildbucket for the build status
[10:02:50.147] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:50.147] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:63:0) after 1m0s
[10:03:50.175] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:63:0)
[10:03:50.175] Timer tick, asking Buildbucket for the build status
[10:03:50.389] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:50.389] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:64:0) after 1m0s
[10:04:50.449] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:64:0)
[10:04:50.449] Timer tick, asking Buildbucket for the build status
[10:04:50.507] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:50.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:65:0) after 1m0s
[10:05:50.544] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:65:0)
[10:05:50.544] Timer tick, asking Buildbucket for the build status
[10:05:50.611] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:50.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:66:0) after 1m0s
[10:06:50.638] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:66:0)
[10:06:50.638] Timer tick, asking Buildbucket for the build status
[10:06:50.845] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.845] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:67:0) after 1m0s
[10:07:50.863] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:67:0)
[10:07:50.863] Timer tick, asking Buildbucket for the build status
[10:07:51.311] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:51.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:68:0) after 1m0s
[10:08:51.332] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:68:0)
[10:08:51.332] Timer tick, asking Buildbucket for the build status
[10:08:51.409] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:51.409] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:69:0) after 1m0s
[10:09:51.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:69:0)
[10:09:51.437] Timer tick, asking Buildbucket for the build status
[10:09:51.501] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:51.501] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:70:0) after 1m0s
[10:10:51.533] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:70:0)
[10:10:51.533] Timer tick, asking Buildbucket for the build status
[10:10:51.995] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:51.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:71:0) after 1m0s
[10:11:52.018] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:71:0)
[10:11:52.018] Timer tick, asking Buildbucket for the build status
[10:11:52.075] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:52.075] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:72:0) after 1m0s
[10:12:52.102] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:72:0)
[10:12:52.102] Timer tick, asking Buildbucket for the build status
[10:12:52.256] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:52.256] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:73:0) after 1m0s
[10:13:52.279] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:73:0)
[10:13:52.279] Timer tick, asking Buildbucket for the build status
[10:13:52.851] Build 8860134662945593536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:52.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9025564725013248896:74:0) after 1m0s
[10:13:55.738] Received PubSub notification, asking Buildbucket for the build status
[10:13:55.823] Build 8860134662945593536: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:13:55.823] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608718434955863",
"created_by": "project:chromium",
"created_ts": "1608714154256492",
"failure_reason": "BUILD_FAILURE",
"id": "8860134662945593536",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2a842e6c83fd9601554537501c6c3a59d3bf836e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.tasks.tab_management.TabSelectionEditorTest#testToolbarNavigationButtonContentDescription\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- org.chromium.chrome.browser.autofill_assistant.AutofillAssistantProgressBarIntegrationTest#testStepProgressBarError\\n\\n- ...17 more failure(s) (21 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.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\": \"2a842e6c83fd9601554537501c6c3a59d3bf836e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-160-yxn1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 6860, \"got_angle_revision\": \"b91afcfd5f7c8bd95999ba644c1151f1bc26f4e5\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"2a842e6c83fd9601554537501c6c3a59d3bf836e\", \"got_revision_cp\": \"refs/heads/master@{#839079}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"5af8c7382f71c6735d1d75cbd056e78e7ccd8862\", \"got_v8_revision_cp\": \"refs/heads/8.9.186@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2a842e6c83fd9601554537501c6c3a59d3bf836e\", \"swarm_hashes_refs/heads/master(at){#839079}_without_patch\": {\"android_browsertests\": \"82a5f6c98027acc1fda644f4a88f3ce43c7e6017\", \"chrome_public_test_apk\": \"f2f1ed62186e39064dcb600c495e6efd4f8518cb\", \"content_browsertests\": \"b524610b6a75017114fdb7818826768e17bbe3c9\", \"content_shell_test_apk\": \"bc3f3cbf12f6fe66e31c115419d60a4df13a36d9\", \"content_unittests\": \"da9ada045b447c2663c4bd91cc341dbe780b7624\", \"unit_tests\": \"15197ccf6ddcbfeff2b049328629e4f0e3873c48\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_6887d226fea03ae8e203d0a1d4c9e197b929dd99abf2bafb38063e06e39bc1ba_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-160-yxn1\"], \"image\": [\"chrome-xenial-20121300-d43316a0aad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.tasks.tab_management.TabSelectionEditorTest#testToolbarNavigationButtonContentDescription\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- org.chromium.chrome.browser.autofill_assistant.AutofillAssistantProgressBarIntegrationTest#testStepProgressBarError\\n\\n- ...17 more failure(s) (21 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608714232861169",
"status": "COMPLETED",
"status_changed_ts": "1608714233187485",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/6860",
"builder:android-bfcache-rel",
"buildset:commit/git/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2a842e6c83fd9601554537501c6c3a59d3bf836e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025564725013248896",
"scheduler_job_id:chromium/android-bfcache-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860134662945593536/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:50a7a77d8ab27210",
"user_agent:luci-scheduler"
],
"updated_ts": "1608718435332651",
"url": "https://ci.chromium.org/b/8860134662945593536",
"utcnow_ts": "1608718435805167"
}
}
[10:13:55.823] Invocation finished in 1h11m22.922929985s with status FAILED