[19:54:09.072] New invocation is queued and will start shortly
[19:54:11.815] Starting the invocation (attempt 1)
[19:54:11.886] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:54:11.886] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:54:11.886] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9040744020083293488",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"eff9ef20f365a1bd5d96a6885ba80db5058eb582\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9040744020083293488",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"gitiles_ref:refs/heads/master"
]
}
[19:54:13.561] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1594238052098584",
"id": "8875313956241449248",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eff9ef20f365a1bd5d96a6885ba80db5058eb582\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594238053232184",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3810",
"builder:android-bfcache-rel",
"buildset:commit/git/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040744020083293488",
"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/8875313956241449248/+/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": "1594238053232367",
"url": "https://ci.chromium.org/b/8875313956241449248",
"utcnow_ts": "1594238053551245"
}
}
[19:54:13.561] Task URL: https://ci.chromium.org/b/8875313956241449248
[19:54:13.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:2:0) after 1m0s
[19:55:13.621] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:2:0)
[19:55:13.621] Timer tick, asking Buildbucket for the build status
[19:55:13.793] Build 8875313956241449248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:13.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:3:0) after 1m0s
[19:55:49.875] Received PubSub notification, asking Buildbucket for the build status
[19:55:49.987] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:13.964] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:3:0)
[19:56:13.964] Timer tick, asking Buildbucket for the build status
[19:56:14.222] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:14.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:5:0) after 1m0s
[19:57:14.318] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:5:0)
[19:57:14.318] Timer tick, asking Buildbucket for the build status
[19:57:14.445] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:14.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:6:0) after 1m0s
[19:58:14.480] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:6:0)
[19:58:14.480] Timer tick, asking Buildbucket for the build status
[19:58:14.939] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:14.939] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:7:0) after 1m0s
[19:59:15.106] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:7:0)
[19:59:15.106] Timer tick, asking Buildbucket for the build status
[19:59:15.356] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:8:0) after 1m0s
[20:00:15.419] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:8:0)
[20:00:15.419] Timer tick, asking Buildbucket for the build status
[20:00:15.661] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:15.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:9:0) after 1m0s
[20:01:15.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:9:0)
[20:01:15.745] Timer tick, asking Buildbucket for the build status
[20:01:15.859] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:15.859] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:10:0) after 1m0s
[20:02:15.905] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:10:0)
[20:02:15.905] Timer tick, asking Buildbucket for the build status
[20:02:16.210] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:16.210] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:11:0) after 1m0s
[20:03:16.213] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:11:0)
[20:03:16.213] Timer tick, asking Buildbucket for the build status
[20:03:16.367] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:16.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:12:0) after 1m0s
[20:04:16.387] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:12:0)
[20:04:16.387] Timer tick, asking Buildbucket for the build status
[20:04:16.564] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:16.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:13:0) after 1m0s
[20:05:16.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:13:0)
[20:05:16.610] Timer tick, asking Buildbucket for the build status
[20:05:16.997] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:16.997] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:14:0) after 1m0s
[20:06:17.026] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:14:0)
[20:06:17.026] Timer tick, asking Buildbucket for the build status
[20:06:18.593] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:18.593] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:15:0) after 1m0s
[20:07:18.640] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:15:0)
[20:07:18.640] Timer tick, asking Buildbucket for the build status
[20:07:18.771] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:18.771] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:16:0) after 1m0s
[20:08:18.961] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:16:0)
[20:08:18.961] Timer tick, asking Buildbucket for the build status
[20:08:19.219] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:19.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:17:0) after 1m0s
[20:09:19.369] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:17:0)
[20:09:19.369] Timer tick, asking Buildbucket for the build status
[20:09:19.989] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:19.989] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:18:0) after 1m0s
[20:10:20.045] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:18:0)
[20:10:20.045] Timer tick, asking Buildbucket for the build status
[20:10:20.796] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:20.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:19:0) after 1m0s
[20:11:20.848] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:19:0)
[20:11:20.848] Timer tick, asking Buildbucket for the build status
[20:11:21.028] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:21.028] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:20:0) after 1m0s
[20:12:21.130] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:20:0)
[20:12:21.130] Timer tick, asking Buildbucket for the build status
[20:12:21.283] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:21.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:21:0) after 1m0s
[20:13:21.307] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:21:0)
[20:13:21.307] Timer tick, asking Buildbucket for the build status
[20:13:21.553] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:21.553] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:22:0) after 1m0s
[20:14:21.582] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:22:0)
[20:14:21.582] Timer tick, asking Buildbucket for the build status
[20:14:21.750] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:21.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:23:0) after 1m0s
[20:15:21.854] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:23:0)
[20:15:21.854] Timer tick, asking Buildbucket for the build status
[20:15:23.454] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:23.454] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:24:0) after 1m0s
[20:16:23.470] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:24:0)
[20:16:23.470] Timer tick, asking Buildbucket for the build status
[20:16:23.612] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:23.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:25:0) after 1m0s
[20:17:23.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:25:0)
[20:17:23.681] Timer tick, asking Buildbucket for the build status
[20:17:24.097] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:26:0) after 1m0s
[20:18:24.447] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:26:0)
[20:18:24.447] Timer tick, asking Buildbucket for the build status
[20:18:24.575] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:24.575] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:27:0) after 1m0s
[20:19:24.881] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:27:0)
[20:19:24.881] Timer tick, asking Buildbucket for the build status
[20:19:24.974] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:24.974] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:28:0) after 1m0s
[20:20:25.105] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:28:0)
[20:20:25.105] Timer tick, asking Buildbucket for the build status
[20:20:25.392] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:25.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:29:0) after 1m0s
[20:21:25.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:29:0)
[20:21:25.529] Timer tick, asking Buildbucket for the build status
[20:21:26.087] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:26.087] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:30:0) after 1m0s
[20:22:26.224] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:30:0)
[20:22:26.224] Timer tick, asking Buildbucket for the build status
[20:22:26.302] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:26.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:31:0) after 1m0s
[20:23:26.454] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:31:0)
[20:23:26.454] Timer tick, asking Buildbucket for the build status
[20:23:26.582] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:26.582] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:32:0) after 1m0s
[20:24:26.736] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:32:0)
[20:24:26.736] Timer tick, asking Buildbucket for the build status
[20:24:27.836] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:27.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:33:0) after 1m0s
[20:25:28.009] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:33:0)
[20:25:28.009] Timer tick, asking Buildbucket for the build status
[20:25:28.098] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:28.098] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:34:0) after 1m0s
[20:26:28.167] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:34:0)
[20:26:28.167] Timer tick, asking Buildbucket for the build status
[20:26:28.623] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:28.623] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:35:0) after 1m0s
[20:27:28.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:35:0)
[20:27:28.644] Timer tick, asking Buildbucket for the build status
[20:27:28.857] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:28.857] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:36:0) after 1m0s
[20:28:28.870] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:36:0)
[20:28:28.870] Timer tick, asking Buildbucket for the build status
[20:28:28.987] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:28.987] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:37:0) after 1m0s
[20:29:29.103] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:37:0)
[20:29:29.103] Timer tick, asking Buildbucket for the build status
[20:29:29.467] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:29.467] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:38:0) after 1m0s
[20:30:29.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:38:0)
[20:30:29.527] Timer tick, asking Buildbucket for the build status
[20:30:30.543] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:30.543] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:39:0) after 1m0s
[20:31:30.659] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:39:0)
[20:31:30.659] Timer tick, asking Buildbucket for the build status
[20:31:31.682] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:31.682] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:40:0) after 1m0s
[20:32:31.725] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:40:0)
[20:32:31.725] Timer tick, asking Buildbucket for the build status
[20:32:31.928] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:31.928] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:41:0) after 1m0s
[20:33:32.005] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:41:0)
[20:33:32.005] Timer tick, asking Buildbucket for the build status
[20:33:32.175] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:32.175] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:42:0) after 1m0s
[20:34:32.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:42:0)
[20:34:32.233] Timer tick, asking Buildbucket for the build status
[20:34:32.428] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:32.428] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:43:0) after 1m0s
[20:35:32.483] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:43:0)
[20:35:32.483] Timer tick, asking Buildbucket for the build status
[20:35:32.649] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:32.649] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:44:0) after 1m0s
[20:36:32.739] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:44:0)
[20:36:32.739] Timer tick, asking Buildbucket for the build status
[20:36:32.989] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:32.989] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:45:0) after 1m0s
[20:37:33.025] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:45:0)
[20:37:33.025] Timer tick, asking Buildbucket for the build status
[20:37:33.146] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:33.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:46:0) after 1m0s
[20:38:33.192] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:46:0)
[20:38:33.192] Timer tick, asking Buildbucket for the build status
[20:38:33.415] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:33.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:47:0) after 1m0s
[20:39:33.526] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:47:0)
[20:39:33.526] Timer tick, asking Buildbucket for the build status
[20:39:33.889] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:48:0) after 1m0s
[20:40:34.098] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:48:0)
[20:40:34.098] Timer tick, asking Buildbucket for the build status
[20:40:34.198] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:34.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:49:0) after 1m0s
[20:41:34.308] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:49:0)
[20:41:34.308] Timer tick, asking Buildbucket for the build status
[20:41:34.632] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:34.632] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:50:0) after 1m0s
[20:42:34.755] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:50:0)
[20:42:34.755] Timer tick, asking Buildbucket for the build status
[20:42:35.879] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:35.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:51:0) after 1m0s
[20:43:35.901] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:51:0)
[20:43:35.901] Timer tick, asking Buildbucket for the build status
[20:43:36.015] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:36.015] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:52:0) after 1m0s
[20:44:36.049] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:52:0)
[20:44:36.049] Timer tick, asking Buildbucket for the build status
[20:44:36.231] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:36.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:53:0) after 1m0s
[20:45:36.262] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:53:0)
[20:45:36.262] Timer tick, asking Buildbucket for the build status
[20:45:36.429] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:36.429] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:54:0) after 1m0s
[20:46:36.474] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:54:0)
[20:46:36.474] Timer tick, asking Buildbucket for the build status
[20:46:36.620] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:36.620] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:55:0) after 1m0s
[20:47:36.707] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:55:0)
[20:47:36.707] Timer tick, asking Buildbucket for the build status
[20:47:36.860] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:36.860] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:56:0) after 1m0s
[20:48:36.864] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:56:0)
[20:48:36.864] Timer tick, asking Buildbucket for the build status
[20:48:38.296] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:38.296] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:57:0) after 1m0s
[20:49:38.326] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:57:0)
[20:49:38.326] Timer tick, asking Buildbucket for the build status
[20:49:38.669] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:38.669] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:58:0) after 1m0s
[20:50:38.709] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:58:0)
[20:50:38.709] Timer tick, asking Buildbucket for the build status
[20:50:38.951] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:38.951] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:59:0) after 1m0s
[20:51:39.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:59:0)
[20:51:39.017] Timer tick, asking Buildbucket for the build status
[20:51:39.085] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:39.085] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:60:0) after 1m0s
[20:52:39.162] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:60:0)
[20:52:39.162] Timer tick, asking Buildbucket for the build status
[20:52:41.689] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:41.689] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:61:0) after 1m0s
[20:53:41.711] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:61:0)
[20:53:41.712] Timer tick, asking Buildbucket for the build status
[20:53:41.955] Build 8875313956241449248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:41.955] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9040744020083293488:62:0) after 1m0s
[20:54:39.029] Received PubSub notification, asking Buildbucket for the build status
[20:54:39.121] Build 8875313956241449248: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:54:39.121] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1594241678546322",
"created_by": "project:chromium",
"created_ts": "1594238052098584",
"failure_reason": "BUILD_FAILURE",
"id": "8875313956241449248",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eff9ef20f365a1bd5d96a6885ba80db5058eb582\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CheckWithFrameCrashDuringNavigation\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/2\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/0\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/1\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-east1-d-8-32-j399\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 3810, \"got_angle_revision\": \"a3f6dc2fadb487a6d53a94e9280d277537b275d6\", \"got_dawn_revision\": \"83fe1bc34d80040a479978acb76a60c5db769b63\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"eff9ef20f365a1bd5d96a6885ba80db5058eb582\", \"got_revision_cp\": \"refs/heads/master@{#786429}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"49a334846e7664d10e496a846b909b5bc2adeb57\", \"got_v8_revision\": \"e078f1d43a5ef91a701f1686917b6fac5176be85\", \"got_v8_revision_cp\": \"refs/heads/8.6.82@{#1}\", \"got_webrtc_revision\": \"a9e1b49704b233cf660cc6670ceaef8ef89f8bb0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31671}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eff9ef20f365a1bd5d96a6885ba80db5058eb582\", \"swarm_hashes\": {\"android_browsertests\": \"71ecdcc4a2c1c0b1a160de83ece194cdc7467e6a\", \"chrome_public_test_apk\": \"f3dd5092c7c9d73984cb4ad5b41410aa7907809a\", \"content_browsertests\": \"1d16f827c76b98fdd6bb417425897d542a09a168\", \"content_shell_test_apk\": \"ce75bd0309adf62e27099059d4afc220b97a2a8d\", \"content_unittests\": \"83e05259ec9403d536cf797202818d18ab90d7c2\", \"unit_tests\": \"cea16f9abc385ea8dc0bfb7e643f40091458a5d3\"}, \"swarm_hashes_refs/heads/master(at){#786429}_without_patch\": {\"android_browsertests\": \"71ecdcc4a2c1c0b1a160de83ece194cdc7467e6a\", \"chrome_public_test_apk\": \"f3dd5092c7c9d73984cb4ad5b41410aa7907809a\", \"content_browsertests\": \"1d16f827c76b98fdd6bb417425897d542a09a168\", \"content_shell_test_apk\": \"ce75bd0309adf62e27099059d4afc220b97a2a8d\", \"content_unittests\": \"83e05259ec9403d536cf797202818d18ab90d7c2\", \"unit_tests\": \"cea16f9abc385ea8dc0bfb7e643f40091458a5d3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-east1-d-8-32-j399\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5320-752003c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CheckWithFrameCrashDuringNavigation\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/2\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/0\\n\\n- All/RenderFrameHostManagerTest.DontSwapProcessWithOnlyRelNoreferrer/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1594238148535762",
"status": "COMPLETED",
"status_changed_ts": "1594238149459527",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3810",
"builder:android-bfcache-rel",
"buildset:commit/git/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eff9ef20f365a1bd5d96a6885ba80db5058eb582",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040744020083293488",
"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/8875313956241449248/+/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:4d48cfb57fccd210",
"user_agent:luci-scheduler"
],
"updated_ts": "1594241678709191",
"url": "https://ci.chromium.org/b/8875313956241449248",
"utcnow_ts": "1594241679111765"
}
}
[20:54:39.121] Invocation finished in 1h0m30.069522868s with status FAILED