[07:20:00.352] New invocation is queued and will start shortly
[07:20:03.522] Starting the invocation (attempt 1)
[07:20:03.562] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:20:03.563] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:20:03.563] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036352215446565328",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8e2e9b2d3242e773ce7184d636c4f21299e7f86a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9036352215446565328",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"gitiles_ref:refs/heads/master"
]
}
[07:20:04.752] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598426403673942",
"id": "8870922151300321760",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e2e9b2d3242e773ce7184d636c4f21299e7f86a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598426404589950",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4693",
"builder:android-bfcache-rel",
"buildset:commit/git/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036352215446565328",
"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/8870922151300321760/+/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": "1598426404590090",
"url": "https://ci.chromium.org/b/8870922151300321760",
"utcnow_ts": "1598426404744910"
}
}
[07:20:04.752] Task URL: https://ci.chromium.org/b/8870922151300321760
[07:20:04.752] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:2:0) after 1m0s
[07:21:05.222] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:2:0)
[07:21:05.222] Timer tick, asking Buildbucket for the build status
[07:21:05.397] Build 8870922151300321760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:05.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:3:0) after 1m0s
[07:22:05.442] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:3:0)
[07:22:05.442] Timer tick, asking Buildbucket for the build status
[07:22:05.540] Build 8870922151300321760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:05.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:4:0) after 1m0s
[07:22:55.714] Received PubSub notification, asking Buildbucket for the build status
[07:22:55.821] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:05.554] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:4:0)
[07:23:05.554] Timer tick, asking Buildbucket for the build status
[07:23:05.681] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:05.681] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:6:0) after 1m0s
[07:24:05.683] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:6:0)
[07:24:05.683] Timer tick, asking Buildbucket for the build status
[07:24:05.787] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:05.787] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:7:0) after 1m0s
[07:25:05.808] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:7:0)
[07:25:05.808] Timer tick, asking Buildbucket for the build status
[07:25:06.007] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:06.007] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:8:0) after 1m0s
[07:26:06.053] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:8:0)
[07:26:06.053] Timer tick, asking Buildbucket for the build status
[07:26:06.484] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:06.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:9:0) after 1m0s
[07:27:06.473] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:9:0)
[07:27:06.473] Timer tick, asking Buildbucket for the build status
[07:27:06.562] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:06.562] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:10:0) after 1m0s
[07:28:06.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:10:0)
[07:28:06.597] Timer tick, asking Buildbucket for the build status
[07:28:06.866] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:06.866] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:11:0) after 1m0s
[07:29:06.911] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:11:0)
[07:29:06.911] Timer tick, asking Buildbucket for the build status
[07:29:07.204] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:07.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:12:0) after 1m0s
[07:30:07.274] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:12:0)
[07:30:07.274] Timer tick, asking Buildbucket for the build status
[07:30:07.492] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:07.492] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:13:0) after 1m0s
[07:31:07.564] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:13:0)
[07:31:07.564] Timer tick, asking Buildbucket for the build status
[07:31:07.662] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:07.662] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:14:0) after 1m0s
[07:32:07.711] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:14:0)
[07:32:07.711] Timer tick, asking Buildbucket for the build status
[07:32:07.776] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:07.776] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:15:0) after 1m0s
[07:33:07.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:15:0)
[07:33:07.869] Timer tick, asking Buildbucket for the build status
[07:33:07.959] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:07.959] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:16:0) after 1m0s
[07:34:07.980] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:16:0)
[07:34:07.980] Timer tick, asking Buildbucket for the build status
[07:34:08.168] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:08.168] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:17:0) after 1m0s
[07:35:08.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:17:0)
[07:35:08.208] Timer tick, asking Buildbucket for the build status
[07:35:08.537] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:08.537] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:18:0) after 1m0s
[07:36:08.558] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:18:0)
[07:36:08.558] Timer tick, asking Buildbucket for the build status
[07:36:08.623] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:08.623] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:19:0) after 1m0s
[07:37:08.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:19:0)
[07:37:08.639] Timer tick, asking Buildbucket for the build status
[07:37:08.736] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:08.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:20:0) after 1m0s
[07:38:08.743] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:20:0)
[07:38:08.743] Timer tick, asking Buildbucket for the build status
[07:38:09.097] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:09.097] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:21:0) after 1m0s
[07:39:09.114] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:21:0)
[07:39:09.114] Timer tick, asking Buildbucket for the build status
[07:39:09.398] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:09.398] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:22:0) after 1m0s
[07:40:09.451] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:22:0)
[07:40:09.451] Timer tick, asking Buildbucket for the build status
[07:40:09.564] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:09.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:23:0) after 1m0s
[07:41:09.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:23:0)
[07:41:09.745] Timer tick, asking Buildbucket for the build status
[07:41:09.874] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:09.874] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:24:0) after 1m0s
[07:42:09.908] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:24:0)
[07:42:09.908] Timer tick, asking Buildbucket for the build status
[07:42:11.031] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:11.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:25:0) after 1m0s
[07:43:11.100] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:25:0)
[07:43:11.100] Timer tick, asking Buildbucket for the build status
[07:43:11.382] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:11.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:26:0) after 1m0s
[07:44:11.431] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:26:0)
[07:44:11.431] Timer tick, asking Buildbucket for the build status
[07:44:12.598] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:12.598] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:27:0) after 1m0s
[07:45:12.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:27:0)
[07:45:12.706] Timer tick, asking Buildbucket for the build status
[07:45:12.797] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:12.797] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:28:0) after 1m0s
[07:46:12.947] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:28:0)
[07:46:12.947] Timer tick, asking Buildbucket for the build status
[07:46:13.285] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:13.285] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:29:0) after 1m0s
[07:47:14.903] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:29:0)
[07:47:14.903] Timer tick, asking Buildbucket for the build status
[07:47:15.149] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:15.149] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:30:0) after 1m0s
[07:48:16.651] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:30:0)
[07:48:16.651] Timer tick, asking Buildbucket for the build status
[07:48:16.717] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:16.717] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:31:0) after 1m0s
[07:49:16.824] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:31:0)
[07:49:16.824] Timer tick, asking Buildbucket for the build status
[07:49:17.466] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:17.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:32:0) after 1m0s
[07:50:17.606] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:32:0)
[07:50:17.606] Timer tick, asking Buildbucket for the build status
[07:50:18.078] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:18.078] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:33:0) after 1m0s
[07:51:18.205] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:33:0)
[07:51:18.205] Timer tick, asking Buildbucket for the build status
[07:51:18.374] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:18.374] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:34:0) after 1m0s
[07:52:18.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:34:0)
[07:52:18.375] Timer tick, asking Buildbucket for the build status
[07:52:18.479] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:18.479] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:35:0) after 1m0s
[07:53:18.523] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:35:0)
[07:53:18.523] Timer tick, asking Buildbucket for the build status
[07:53:18.645] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:18.645] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:36:0) after 1m0s
[07:54:18.713] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:36:0)
[07:54:18.713] Timer tick, asking Buildbucket for the build status
[07:54:18.775] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:18.775] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:37:0) after 1m0s
[07:55:18.904] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:37:0)
[07:55:18.904] Timer tick, asking Buildbucket for the build status
[07:55:19.098] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:19.098] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:38:0) after 1m0s
[07:56:19.125] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:38:0)
[07:56:19.125] Timer tick, asking Buildbucket for the build status
[07:56:19.528] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:19.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:39:0) after 1m0s
[07:57:19.571] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:39:0)
[07:57:19.571] Timer tick, asking Buildbucket for the build status
[07:57:19.629] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:40:0) after 1m0s
[07:58:19.646] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:40:0)
[07:58:19.646] Timer tick, asking Buildbucket for the build status
[07:58:19.861] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:19.861] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:41:0) after 1m0s
[07:59:19.952] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:41:0)
[07:59:19.952] Timer tick, asking Buildbucket for the build status
[07:59:20.036] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.036] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:42:0) after 1m0s
[08:00:20.098] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:42:0)
[08:00:20.098] Timer tick, asking Buildbucket for the build status
[08:00:20.660] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.660] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:43:0) after 1m0s
[08:01:20.751] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:43:0)
[08:01:20.751] Timer tick, asking Buildbucket for the build status
[08:01:21.211] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.211] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:44:0) after 1m0s
[08:02:21.259] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:44:0)
[08:02:21.259] Timer tick, asking Buildbucket for the build status
[08:02:21.353] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:21.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:45:0) after 1m0s
[08:03:21.450] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:45:0)
[08:03:21.450] Timer tick, asking Buildbucket for the build status
[08:03:21.843] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:21.843] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:46:0) after 1m0s
[08:04:21.856] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:46:0)
[08:04:21.856] Timer tick, asking Buildbucket for the build status
[08:04:22.138] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.138] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:47:0) after 1m0s
[08:05:22.247] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:47:0)
[08:05:22.247] Timer tick, asking Buildbucket for the build status
[08:05:22.397] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:22.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:48:0) after 1m0s
[08:06:22.451] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:48:0)
[08:06:22.451] Timer tick, asking Buildbucket for the build status
[08:06:22.699] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:22.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:49:0) after 1m0s
[08:07:22.742] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:49:0)
[08:07:22.742] Timer tick, asking Buildbucket for the build status
[08:07:22.891] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:22.891] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:50:0) after 1m0s
[08:08:22.973] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:50:0)
[08:08:22.973] Timer tick, asking Buildbucket for the build status
[08:08:23.166] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:51:0) after 1m0s
[08:09:23.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:51:0)
[08:09:23.206] Timer tick, asking Buildbucket for the build status
[08:09:23.374] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:23.374] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:52:0) after 1m0s
[08:10:23.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:52:0)
[08:10:23.455] Timer tick, asking Buildbucket for the build status
[08:10:23.566] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:23.566] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:53:0) after 1m0s
[08:11:23.827] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:53:0)
[08:11:23.827] Timer tick, asking Buildbucket for the build status
[08:11:24.283] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:24.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:54:0) after 1m0s
[08:12:24.518] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:54:0)
[08:12:24.518] Timer tick, asking Buildbucket for the build status
[08:12:24.617] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:24.618] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:55:0) after 1m0s
[08:13:24.910] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:55:0)
[08:13:24.910] Timer tick, asking Buildbucket for the build status
[08:13:25.165] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:25.165] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:56:0) after 1m0s
[08:14:25.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:56:0)
[08:14:25.286] Timer tick, asking Buildbucket for the build status
[08:14:25.367] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:25.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:57:0) after 1m0s
[08:15:25.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:57:0)
[08:15:25.477] Timer tick, asking Buildbucket for the build status
[08:15:25.653] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:25.653] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:58:0) after 1m0s
[08:16:25.705] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:58:0)
[08:16:25.705] Timer tick, asking Buildbucket for the build status
[08:16:25.886] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:25.886] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:59:0) after 1m0s
[08:17:25.985] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:59:0)
[08:17:25.985] Timer tick, asking Buildbucket for the build status
[08:17:26.075] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:26.075] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:60:0) after 1m0s
[08:18:26.174] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:60:0)
[08:18:26.174] Timer tick, asking Buildbucket for the build status
[08:18:26.266] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:26.266] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:61:0) after 1m0s
[08:19:26.420] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:61:0)
[08:19:26.420] Timer tick, asking Buildbucket for the build status
[08:19:26.497] Build 8870922151300321760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:26.497] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036352215446565328:62:0) after 1m0s
[08:19:58.804] Received PubSub notification, asking Buildbucket for the build status
[08:19:58.863] Build 8870922151300321760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:19:58.863] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598429997789372",
"created_by": "project:chromium",
"created_ts": "1598426403673942",
"failure_reason": "BUILD_FAILURE",
"id": "8870922151300321760",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e2e9b2d3242e773ce7184d636c4f21299e7f86a\"}}",
"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.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSearchString\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSelfIconCompressed\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testTelephonesRemoved\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSelectAll\\n\\n- ...15 more failure(s) (19 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"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-21-4fcs\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4693, \"got_angle_revision\": \"d19c08c9ed8ded0306039af4cdc4a93ce18e3742\", \"got_dawn_revision\": \"c3e3c30b0d7f51064f7247a09b61d208252416b7\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"8e2e9b2d3242e773ce7184d636c4f21299e7f86a\", \"got_revision_cp\": \"refs/heads/master@{#801667}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"622558b02e1a5441c579994831a6338ffaf2de5c\", \"got_v8_revision\": \"30f6538a90d235161498f6d43b087057cefd75a8\", \"got_v8_revision_cp\": \"refs/heads/8.7.18@{#1}\", \"got_webrtc_revision\": \"59230836579a7ce0386faebb07c73ac23f31a2b4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31987}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e2e9b2d3242e773ce7184d636c4f21299e7f86a\", \"swarm_hashes\": {\"android_browsertests\": \"e39babbadd659563b3d1804470818bf0442aa3bb\", \"chrome_public_test_apk\": \"abbbf4439699840d9277afcae2eacc671ce5f738\", \"content_browsertests\": \"d4ce97c2e96a84c335cb5b88bc90992c33ce8247\", \"content_shell_test_apk\": \"669f537c8f72255d0957d6eb09f057fd2e2fdf40\", \"content_unittests\": \"b2548377e7ec881246b1f8121e89645e08fb172f\", \"unit_tests\": \"57969d854f8d59847d5d0c9b064f4e5a621f36b9\"}, \"swarm_hashes_refs/heads/master(at){#801667}_without_patch\": {\"android_browsertests\": \"e39babbadd659563b3d1804470818bf0442aa3bb\", \"chrome_public_test_apk\": \"abbbf4439699840d9277afcae2eacc671ce5f738\", \"content_browsertests\": \"d4ce97c2e96a84c335cb5b88bc90992c33ce8247\", \"content_shell_test_apk\": \"669f537c8f72255d0957d6eb09f057fd2e2fdf40\", \"content_unittests\": \"b2548377e7ec881246b1f8121e89645e08fb172f\", \"unit_tests\": \"57969d854f8d59847d5d0c9b064f4e5a621f36b9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_08506e22a0e0fdc29bd318165699b599f0671cf91287ea7a96642b02d7ff24e4_v2\", \"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_1d6807bf68beb1f6e53b053acbc5bd9e728357249a14ae6e1a69ace8071ddc84_v2\", \"builder_953e900270162ec95f568ea264af47d43b9a29a15e01ede040ffa4d15b188919_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_beb776337cf5e2ff0f3cb2908a8270f1e35b65aa6652b9e320dd7c6c902817db_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_v2\", \"builder_f47d69f9f4bf9e7d220a25d2143ab9b11dd6623550a4bb4bad1124cef443bede_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-21-4fcs\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"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.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSearchString\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSelfIconCompressed\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testTelephonesRemoved\\n\\n- org.chromium.components.browser_ui.contacts_picker.ContactsPickerDialogTest#testSelectAll\\n\\n- ...15 more failure(s) (19 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598426575137746",
"status": "COMPLETED",
"status_changed_ts": "1598426575441060",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4693",
"builder:android-bfcache-rel",
"buildset:commit/git/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e2e9b2d3242e773ce7184d636c4f21299e7f86a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036352215446565328",
"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/8870922151300321760/+/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:4e4274dfffd35110",
"user_agent:luci-scheduler"
],
"updated_ts": "1598429998368404",
"url": "https://ci.chromium.org/b/8870922151300321760",
"utcnow_ts": "1598429998855578"
}
}
[08:19:58.863] Invocation finished in 59m58.528142037s with status FAILED