[17:14:05.126] New invocation is queued and will start shortly
[17:14:06.352] Starting the invocation (attempt 1)
[17:14:06.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:14:06.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:14:06.442] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9044921551002124208",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c28b9dadccea9ad14ea59fadef988c41760d50e0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9044921551002124208",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"gitiles_ref:refs/heads/master"
]
}
[17:14:07.725] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1590254046615304",
"id": "8879491488774810480",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c28b9dadccea9ad14ea59fadef988c41760d50e0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590254047428834",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/2889",
"builder:android-bfcache-rel",
"buildset:commit/git/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044921551002124208",
"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/8879491488774810480/+/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": "1590254047429212",
"url": "https://ci.chromium.org/b/8879491488774810480",
"utcnow_ts": "1590254047714458"
}
}
[17:14:07.725] Task URL: https://ci.chromium.org/b/8879491488774810480
[17:14:07.725] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:2:0) after 1m0s
[17:15:07.741] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:2:0)
[17:15:07.741] Timer tick, asking Buildbucket for the build status
[17:15:07.911] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:15:07.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:3:0) after 1m0s
[17:16:07.961] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:3:0)
[17:16:07.961] Timer tick, asking Buildbucket for the build status
[17:16:08.258] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:16:08.258] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:4:0) after 1m0s
[17:17:08.232] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:4:0)
[17:17:08.232] Timer tick, asking Buildbucket for the build status
[17:17:08.462] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:17:08.462] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:5:0) after 1m0s
[17:18:08.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:5:0)
[17:18:08.639] Timer tick, asking Buildbucket for the build status
[17:18:09.374] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:18:09.374] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:6:0) after 1m0s
[17:19:09.488] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:6:0)
[17:19:09.488] Timer tick, asking Buildbucket for the build status
[17:19:09.904] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:19:09.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:7:0) after 1m0s
[17:20:09.903] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:7:0)
[17:20:09.904] Timer tick, asking Buildbucket for the build status
[17:20:10.168] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:20:10.168] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:8:0) after 1m0s
[17:21:10.187] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:8:0)
[17:21:10.187] Timer tick, asking Buildbucket for the build status
[17:21:10.746] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:10.746] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:9:0) after 1m0s
[17:22:10.816] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:9:0)
[17:22:10.816] Timer tick, asking Buildbucket for the build status
[17:22:11.338] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:11.338] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:10:0) after 1m0s
[17:23:11.449] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:10:0)
[17:23:11.449] Timer tick, asking Buildbucket for the build status
[17:23:11.612] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:11.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:11:0) after 1m0s
[17:24:11.680] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:11:0)
[17:24:11.680] Timer tick, asking Buildbucket for the build status
[17:24:11.969] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:11.969] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:12:0) after 1m0s
[17:25:12.113] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:12:0)
[17:25:12.113] Timer tick, asking Buildbucket for the build status
[17:25:12.453] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:12.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:13:0) after 1m0s
[17:26:12.481] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:13:0)
[17:26:12.481] Timer tick, asking Buildbucket for the build status
[17:26:12.686] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:12.686] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:14:0) after 1m0s
[17:27:12.710] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:14:0)
[17:27:12.710] Timer tick, asking Buildbucket for the build status
[17:27:12.909] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:27:12.909] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:15:0) after 1m0s
[17:28:12.980] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:15:0)
[17:28:12.980] Timer tick, asking Buildbucket for the build status
[17:28:13.175] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:28:13.175] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:16:0) after 1m0s
[17:29:13.213] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:16:0)
[17:29:13.213] Timer tick, asking Buildbucket for the build status
[17:29:13.388] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:29:13.388] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:17:0) after 1m0s
[17:30:13.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:17:0)
[17:30:13.477] Timer tick, asking Buildbucket for the build status
[17:30:13.745] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:13.745] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:18:0) after 1m0s
[17:31:13.769] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:18:0)
[17:31:13.769] Timer tick, asking Buildbucket for the build status
[17:31:14.182] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:14.182] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:19:0) after 1m0s
[17:32:14.307] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:19:0)
[17:32:14.307] Timer tick, asking Buildbucket for the build status
[17:32:14.427] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:14.427] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:20:0) after 1m0s
[17:33:14.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:20:0)
[17:33:14.477] Timer tick, asking Buildbucket for the build status
[17:33:14.662] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:14.662] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:21:0) after 1m0s
[17:34:14.688] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:21:0)
[17:34:14.688] Timer tick, asking Buildbucket for the build status
[17:34:15.084] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:15.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:22:0) after 1m0s
[17:35:15.125] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:22:0)
[17:35:15.125] Timer tick, asking Buildbucket for the build status
[17:35:15.276] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:15.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:23:0) after 1m0s
[17:36:15.419] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:23:0)
[17:36:15.419] Timer tick, asking Buildbucket for the build status
[17:36:15.550] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:15.550] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:24:0) after 1m0s
[17:37:15.575] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:24:0)
[17:37:15.575] Timer tick, asking Buildbucket for the build status
[17:37:15.731] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:15.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:25:0) after 1m0s
[17:38:15.761] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:25:0)
[17:38:15.761] Timer tick, asking Buildbucket for the build status
[17:38:16.185] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:16.185] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:26:0) after 1m0s
[17:39:16.204] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:26:0)
[17:39:16.204] Timer tick, asking Buildbucket for the build status
[17:39:16.302] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:16.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:27:0) after 1m0s
[17:40:16.339] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:27:0)
[17:40:16.339] Timer tick, asking Buildbucket for the build status
[17:40:16.512] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:16.512] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:28:0) after 1m0s
[17:41:16.497] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:28:0)
[17:41:16.497] Timer tick, asking Buildbucket for the build status
[17:41:16.881] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:16.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:29:0) after 1m0s
[17:42:17.001] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:29:0)
[17:42:17.001] Timer tick, asking Buildbucket for the build status
[17:42:17.755] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:17.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:30:0) after 1m0s
[17:43:17.776] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:30:0)
[17:43:17.776] Timer tick, asking Buildbucket for the build status
[17:43:17.919] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:17.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:31:0) after 1m0s
[17:44:17.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:31:0)
[17:44:17.941] Timer tick, asking Buildbucket for the build status
[17:44:18.062] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:44:18.062] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:32:0) after 1m0s
[17:45:18.093] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:32:0)
[17:45:18.093] Timer tick, asking Buildbucket for the build status
[17:45:18.307] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:18.307] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:33:0) after 1m0s
[17:46:18.330] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:33:0)
[17:46:18.330] Timer tick, asking Buildbucket for the build status
[17:46:18.569] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:18.569] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:34:0) after 1m0s
[17:47:18.602] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:34:0)
[17:47:18.602] Timer tick, asking Buildbucket for the build status
[17:47:18.746] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:18.746] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:35:0) after 1m0s
[17:48:18.768] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:35:0)
[17:48:18.768] Timer tick, asking Buildbucket for the build status
[17:48:18.892] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:18.892] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:36:0) after 1m0s
[17:49:18.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:36:0)
[17:49:18.915] Timer tick, asking Buildbucket for the build status
[17:49:19.045] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:19.045] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:37:0) after 1m0s
[17:50:19.074] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:37:0)
[17:50:19.075] Timer tick, asking Buildbucket for the build status
[17:50:19.363] Build 8879491488774810480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:19.364] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:38:0) after 1m0s
[17:50:59.176] Received PubSub notification, asking Buildbucket for the build status
[17:50:59.305] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:19.410] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:38:0)
[17:51:19.410] Timer tick, asking Buildbucket for the build status
[17:51:19.560] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:19.560] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:40:0) after 1m0s
[17:52:19.540] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:40:0)
[17:52:19.540] Timer tick, asking Buildbucket for the build status
[17:52:19.657] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:41:0) after 1m0s
[17:53:19.724] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:41:0)
[17:53:19.724] Timer tick, asking Buildbucket for the build status
[17:53:19.851] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:19.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:42:0) after 1m0s
[17:54:19.970] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:42:0)
[17:54:19.970] Timer tick, asking Buildbucket for the build status
[17:54:20.140] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:20.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:43:0) after 1m0s
[17:55:20.242] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:43:0)
[17:55:20.242] Timer tick, asking Buildbucket for the build status
[17:55:20.455] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:20.455] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:44:0) after 1m0s
[17:56:20.624] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:44:0)
[17:56:20.624] Timer tick, asking Buildbucket for the build status
[17:56:20.793] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:20.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:45:0) after 1m0s
[17:57:20.864] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:45:0)
[17:57:20.864] Timer tick, asking Buildbucket for the build status
[17:57:21.172] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:21.172] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:46:0) after 1m0s
[17:58:21.194] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:46:0)
[17:58:21.194] Timer tick, asking Buildbucket for the build status
[17:58:21.656] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:21.656] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:47:0) after 1m0s
[17:59:21.732] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:47:0)
[17:59:21.732] Timer tick, asking Buildbucket for the build status
[17:59:22.188] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:22.188] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:48:0) after 1m0s
[18:00:22.255] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:48:0)
[18:00:22.255] Timer tick, asking Buildbucket for the build status
[18:00:22.785] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:22.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:49:0) after 1m0s
[18:01:22.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:49:0)
[18:01:22.784] Timer tick, asking Buildbucket for the build status
[18:01:22.998] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:22.998] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:50:0) after 1m0s
[18:02:23.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:50:0)
[18:02:23.043] Timer tick, asking Buildbucket for the build status
[18:02:23.348] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:23.348] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:51:0) after 1m0s
[18:03:23.327] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:51:0)
[18:03:23.327] Timer tick, asking Buildbucket for the build status
[18:03:23.549] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:23.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:52:0) after 1m0s
[18:04:23.552] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:52:0)
[18:04:23.552] Timer tick, asking Buildbucket for the build status
[18:04:23.697] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:23.698] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:53:0) after 1m0s
[18:05:23.811] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:53:0)
[18:05:23.811] Timer tick, asking Buildbucket for the build status
[18:05:24.435] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:24.435] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:54:0) after 1m0s
[18:06:24.500] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:54:0)
[18:06:24.500] Timer tick, asking Buildbucket for the build status
[18:06:24.634] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:24.634] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:55:0) after 1m0s
[18:07:24.735] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:55:0)
[18:07:24.735] Timer tick, asking Buildbucket for the build status
[18:07:24.908] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:24.908] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:56:0) after 1m0s
[18:08:24.983] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:56:0)
[18:08:24.983] Timer tick, asking Buildbucket for the build status
[18:08:26.410] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:26.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:57:0) after 1m0s
[18:09:26.518] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:57:0)
[18:09:26.518] Timer tick, asking Buildbucket for the build status
[18:09:26.726] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:26.726] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:58:0) after 1m0s
[18:10:26.836] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:58:0)
[18:10:26.836] Timer tick, asking Buildbucket for the build status
[18:10:27.146] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:27.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:59:0) after 1m0s
[18:11:27.212] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:59:0)
[18:11:27.212] Timer tick, asking Buildbucket for the build status
[18:11:27.566] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:27.566] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:60:0) after 1m0s
[18:12:27.589] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:60:0)
[18:12:27.589] Timer tick, asking Buildbucket for the build status
[18:12:28.011] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:28.011] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:61:0) after 1m0s
[18:13:28.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:61:0)
[18:13:28.034] Timer tick, asking Buildbucket for the build status
[18:13:28.334] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:28.334] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:62:0) after 1m0s
[18:14:28.405] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:62:0)
[18:14:28.405] Timer tick, asking Buildbucket for the build status
[18:14:28.671] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:28.671] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:63:0) after 1m0s
[18:15:28.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:63:0)
[18:15:28.757] Timer tick, asking Buildbucket for the build status
[18:15:29.066] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:29.066] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:64:0) after 1m0s
[18:16:29.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:64:0)
[18:16:29.083] Timer tick, asking Buildbucket for the build status
[18:16:29.223] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:29.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:65:0) after 1m0s
[18:17:29.298] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:65:0)
[18:17:29.298] Timer tick, asking Buildbucket for the build status
[18:17:29.781] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:29.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:66:0) after 1m0s
[18:18:29.803] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:66:0)
[18:18:29.803] Timer tick, asking Buildbucket for the build status
[18:18:29.951] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:29.951] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:67:0) after 1m0s
[18:19:30.042] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:67:0)
[18:19:30.042] Timer tick, asking Buildbucket for the build status
[18:19:30.401] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:30.401] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:68:0) after 1m0s
[18:20:30.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:68:0)
[18:20:30.428] Timer tick, asking Buildbucket for the build status
[18:20:30.838] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:30.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:69:0) after 1m0s
[18:21:30.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:69:0)
[18:21:30.954] Timer tick, asking Buildbucket for the build status
[18:21:32.512] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:32.512] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:70:0) after 1m0s
[18:22:32.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:70:0)
[18:22:32.493] Timer tick, asking Buildbucket for the build status
[18:22:32.826] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:32.826] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:71:0) after 1m0s
[18:23:32.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:71:0)
[18:23:32.859] Timer tick, asking Buildbucket for the build status
[18:23:33.181] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:33.181] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:72:0) after 1m0s
[18:24:33.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:72:0)
[18:24:33.233] Timer tick, asking Buildbucket for the build status
[18:24:33.475] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:33.475] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:73:0) after 1m0s
[18:25:33.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:73:0)
[18:25:33.519] Timer tick, asking Buildbucket for the build status
[18:25:33.764] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:33.764] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:74:0) after 1m0s
[18:26:33.819] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:74:0)
[18:26:33.819] Timer tick, asking Buildbucket for the build status
[18:26:33.970] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:33.970] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:75:0) after 1m0s
[18:27:33.996] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:75:0)
[18:27:33.996] Timer tick, asking Buildbucket for the build status
[18:27:34.275] Build 8879491488774810480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:34.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9044921551002124208:76:0) after 1m0s
[18:27:58.847] Received PubSub notification, asking Buildbucket for the build status
[18:28:00.312] Build 8879491488774810480: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:28:00.312] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1590258474004895",
"created_by": "project:chromium",
"created_ts": "1590254046615304",
"failure_reason": "BUILD_FAILURE",
"id": "8879491488774810480",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c28b9dadccea9ad14ea59fadef988c41760d50e0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__Instant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceLayoutTest#testTabGroupManualSelection_AfterReviewTabSuggestion\\n\\n- ...117 more failure(s) (121 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- SitePerProcessBrowserTest.NavigateMainFrameToChildSite\\n\\n- ...26 more failure(s) (28 total)...\\n\\n#### ...1 more test(s)...\"}, \"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-64-79ql\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 2889, \"got_angle_revision\": \"d3d7b95bac1957900d346637600adc9eacf1fd64\", \"got_dawn_revision\": \"65ee6497d620110546181c1dfbd0cc3064f9f7f9\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"c28b9dadccea9ad14ea59fadef988c41760d50e0\", \"got_revision_cp\": \"refs/heads/master@{#771400}\", \"got_swarming_client_revision\": \"7e8636295f4763a5f5aace1e700b17a4af3c81b7\", \"got_swiftshader_revision\": \"cc5cda0f997d8280712920f2749bbdfe7a0f4dc3\", \"got_v8_revision\": \"414d114aaaad4f0cac357eb2705a71e3ca7bf898\", \"got_v8_revision_cp\": \"refs/heads/8.5.34@{#1}\", \"got_webrtc_revision\": \"7eccfc6933c7a7bc7a8af82faa45b2b96c87083e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31338}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c28b9dadccea9ad14ea59fadef988c41760d50e0\", \"swarm_hashes\": {\"android_browsertests\": \"14e25c70b4ed5d99ec55670f3a8a2d091b92f64a\", \"chrome_public_test_apk\": \"7dfe113ad26e85b225cd440b9a38461837684d08\", \"content_browsertests\": \"6da5e9efcdfc072b54edcbed6ca2a130c0321c07\", \"content_shell_test_apk\": \"1ca1ce51dbf053e01a880f983b5fbc00cdde8e98\", \"content_unittests\": \"a795757159a6efb0d21de5565e6009007d4b6c0b\", \"unit_tests\": \"50d265c1ab6d465e373710c0503e52468434894f\"}, \"swarm_hashes_refs/heads/master(at){#771400}_without_patch\": {\"android_browsertests\": \"14e25c70b4ed5d99ec55670f3a8a2d091b92f64a\", \"chrome_public_test_apk\": \"7dfe113ad26e85b225cd440b9a38461837684d08\", \"content_browsertests\": \"6da5e9efcdfc072b54edcbed6ca2a130c0321c07\", \"content_shell_test_apk\": \"1ca1ce51dbf053e01a880f983b5fbc00cdde8e98\", \"content_unittests\": \"a795757159a6efb0d21de5565e6009007d4b6c0b\", \"unit_tests\": \"50d265c1ab6d465e373710c0503e52468434894f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_881ff82fecab264f5d0c0106259916880a9b15a60f4edadb4f36a488ce43948f_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_f5174a4d80cdc4c8aa1989fa45d69979d28910f768af140dd646791c34143bfe_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-64-79ql\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__Instant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceLayoutTest#testTabGroupManualSelection_AfterReviewTabSuggestion\\n\\n- ...117 more failure(s) (121 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- SitePerProcessBrowserTest.NavigateMainFrameToChildSite\\n\\n- ...26 more failure(s) (28 total)...\\n\\n#### ...1 more test(s)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1590256257768526",
"status": "COMPLETED",
"status_changed_ts": "1590256258342506",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/2889",
"builder:android-bfcache-rel",
"buildset:commit/git/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c28b9dadccea9ad14ea59fadef988c41760d50e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044921551002124208",
"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/8879491488774810480/+/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:4c5b589b4b941910",
"user_agent:luci-scheduler"
],
"updated_ts": "1590258477392256",
"url": "https://ci.chromium.org/b/8879491488774810480",
"utcnow_ts": "1590258480284281"
}
}
[18:28:00.312] Invocation finished in 1h13m55.20516967s with status FAILED