[03:23:10.414] New invocation is queued and will start shortly
[03:23:11.995] Starting the invocation (attempt 1)
[03:23:12.036] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:23:12.036] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:23:12.036] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036185921712739664",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d4ac1a9f6150a21efa8e6ce2778f8123db60794\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9036185921712739664",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"gitiles_ref:refs/heads/master"
]
}
[03:23:12.590] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1598584992144285",
"id": "8870755859235583440",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d4ac1a9f6150a21efa8e6ce2778f8123db60794\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598584992488280",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4723",
"builder:android-bfcache-rel",
"buildset:commit/git/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036185921712739664",
"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/8870755859235583440/+/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": "1598584992488487",
"url": "https://ci.chromium.org/b/8870755859235583440",
"utcnow_ts": "1598584992583000"
}
}
[03:23:12.590] Task URL: https://ci.chromium.org/b/8870755859235583440
[03:23:12.590] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:2:0) after 1m0s
[03:24:12.664] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:2:0)
[03:24:12.664] Timer tick, asking Buildbucket for the build status
[03:24:13.014] Build 8870755859235583440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:13.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:3:0) after 1m0s
[03:25:13.119] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:3:0)
[03:25:13.119] Timer tick, asking Buildbucket for the build status
[03:25:14.202] Build 8870755859235583440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:14.202] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:4:0) after 1m0s
[03:26:13.728] Received PubSub notification, asking Buildbucket for the build status
[03:26:14.039] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:14.292] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:4:0)
[03:26:14.292] Timer tick, asking Buildbucket for the build status
[03:26:14.386] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:14.386] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:6:0) after 1m0s
[03:27:14.453] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:6:0)
[03:27:14.453] Timer tick, asking Buildbucket for the build status
[03:27:14.562] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:14.562] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:7:0) after 1m0s
[03:28:14.656] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:7:0)
[03:28:14.656] Timer tick, asking Buildbucket for the build status
[03:28:14.812] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:14.812] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:8:0) after 1m0s
[03:29:14.881] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:8:0)
[03:29:14.881] Timer tick, asking Buildbucket for the build status
[03:29:15.420] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:15.420] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:9:0) after 1m0s
[03:30:15.688] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:9:0)
[03:30:15.688] Timer tick, asking Buildbucket for the build status
[03:30:15.804] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:15.804] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:10:0) after 1m0s
[03:31:16.008] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:10:0)
[03:31:16.008] Timer tick, asking Buildbucket for the build status
[03:31:16.165] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:16.165] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:11:0) after 1m0s
[03:32:16.371] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:11:0)
[03:32:16.371] Timer tick, asking Buildbucket for the build status
[03:32:16.605] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:16.605] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:12:0) after 1m0s
[03:33:16.759] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:12:0)
[03:33:16.759] Timer tick, asking Buildbucket for the build status
[03:33:16.873] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:16.873] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:13:0) after 1m0s
[03:34:17.065] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:13:0)
[03:34:17.065] Timer tick, asking Buildbucket for the build status
[03:34:17.196] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:17.196] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:14:0) after 1m0s
[03:35:17.361] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:14:0)
[03:35:17.361] Timer tick, asking Buildbucket for the build status
[03:35:17.427] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:17.427] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:15:0) after 1m0s
[03:36:17.711] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:15:0)
[03:36:17.711] Timer tick, asking Buildbucket for the build status
[03:36:17.836] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:17.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:16:0) after 1m0s
[03:37:17.881] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:16:0)
[03:37:17.881] Timer tick, asking Buildbucket for the build status
[03:37:17.987] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:17.987] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:17:0) after 1m0s
[03:38:18.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:17:0)
[03:38:18.208] Timer tick, asking Buildbucket for the build status
[03:38:18.360] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:18.360] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:18:0) after 1m0s
[03:39:18.546] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:18:0)
[03:39:18.546] Timer tick, asking Buildbucket for the build status
[03:39:18.618] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:18.618] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:19:0) after 1m0s
[03:40:18.764] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:19:0)
[03:40:18.764] Timer tick, asking Buildbucket for the build status
[03:40:19.109] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:19.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:20:0) after 1m0s
[03:41:19.288] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:20:0)
[03:41:19.288] Timer tick, asking Buildbucket for the build status
[03:41:19.373] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:19.373] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:21:0) after 1m0s
[03:42:19.497] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:21:0)
[03:42:19.497] Timer tick, asking Buildbucket for the build status
[03:42:19.890] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:19.890] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:22:0) after 1m0s
[03:43:19.973] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:22:0)
[03:43:19.973] Timer tick, asking Buildbucket for the build status
[03:43:20.079] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:20.079] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:23:0) after 1m0s
[03:44:20.107] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:23:0)
[03:44:20.107] Timer tick, asking Buildbucket for the build status
[03:44:20.228] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:20.228] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:24:0) after 1m0s
[03:45:20.284] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:24:0)
[03:45:20.284] Timer tick, asking Buildbucket for the build status
[03:45:20.370] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:20.370] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:25:0) after 1m0s
[03:46:20.368] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:25:0)
[03:46:20.368] Timer tick, asking Buildbucket for the build status
[03:46:20.452] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:20.452] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:26:0) after 1m0s
[03:47:20.520] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:26:0)
[03:47:20.520] Timer tick, asking Buildbucket for the build status
[03:47:20.588] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:20.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:27:0) after 1m0s
[03:48:20.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:27:0)
[03:48:20.750] Timer tick, asking Buildbucket for the build status
[03:48:20.997] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:20.997] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:28:0) after 1m0s
[03:49:21.066] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:28:0)
[03:49:21.066] Timer tick, asking Buildbucket for the build status
[03:49:21.142] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:21.142] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:29:0) after 1m0s
[03:50:21.289] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:29:0)
[03:50:21.289] Timer tick, asking Buildbucket for the build status
[03:50:21.382] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:21.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:30:0) after 1m0s
[03:51:22.068] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:30:0)
[03:51:22.068] Timer tick, asking Buildbucket for the build status
[03:51:22.174] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:22.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:31:0) after 1m0s
[03:52:22.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:31:0)
[03:52:22.303] Timer tick, asking Buildbucket for the build status
[03:52:22.611] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:22.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:32:0) after 1m0s
[03:53:22.677] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:32:0)
[03:53:22.677] Timer tick, asking Buildbucket for the build status
[03:53:22.780] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:22.780] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:33:0) after 1m0s
[03:54:22.862] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:33:0)
[03:54:22.862] Timer tick, asking Buildbucket for the build status
[03:54:22.935] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:22.935] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:34:0) after 1m0s
[03:55:22.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:34:0)
[03:55:22.943] Timer tick, asking Buildbucket for the build status
[03:55:23.010] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:23.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:35:0) after 1m0s
[03:56:23.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:35:0)
[03:56:23.061] Timer tick, asking Buildbucket for the build status
[03:56:23.151] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:23.151] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:36:0) after 1m0s
[03:57:23.230] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:36:0)
[03:57:23.230] Timer tick, asking Buildbucket for the build status
[03:57:23.624] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:23.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:37:0) after 1m0s
[03:58:23.687] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:37:0)
[03:58:23.687] Timer tick, asking Buildbucket for the build status
[03:58:23.830] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:23.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:38:0) after 1m0s
[03:59:23.973] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:38:0)
[03:59:23.973] Timer tick, asking Buildbucket for the build status
[03:59:24.035] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:24.035] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:39:0) after 1m0s
[04:00:24.167] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:39:0)
[04:00:24.167] Timer tick, asking Buildbucket for the build status
[04:00:24.322] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:24.322] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:40:0) after 1m0s
[04:01:24.398] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:40:0)
[04:01:24.398] Timer tick, asking Buildbucket for the build status
[04:01:24.455] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:24.455] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:41:0) after 1m0s
[04:02:24.563] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:41:0)
[04:02:24.563] Timer tick, asking Buildbucket for the build status
[04:02:26.088] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.088] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:42:0) after 1m0s
[04:03:26.147] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:42:0)
[04:03:26.147] Timer tick, asking Buildbucket for the build status
[04:03:26.305] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.305] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:43:0) after 1m0s
[04:04:26.339] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:43:0)
[04:04:26.339] Timer tick, asking Buildbucket for the build status
[04:04:26.487] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:26.487] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:44:0) after 1m0s
[04:05:26.512] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:44:0)
[04:05:26.512] Timer tick, asking Buildbucket for the build status
[04:05:26.664] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:26.664] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:45:0) after 1m0s
[04:06:26.766] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:45:0)
[04:06:26.766] Timer tick, asking Buildbucket for the build status
[04:06:26.893] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:26.893] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:46:0) after 1m0s
[04:07:27.018] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:46:0)
[04:07:27.018] Timer tick, asking Buildbucket for the build status
[04:07:27.185] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.185] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:47:0) after 1m0s
[04:08:27.198] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:47:0)
[04:08:27.198] Timer tick, asking Buildbucket for the build status
[04:08:27.699] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:48:0) after 1m0s
[04:09:27.831] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:48:0)
[04:09:27.831] Timer tick, asking Buildbucket for the build status
[04:09:27.953] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:27.953] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:49:0) after 1m0s
[04:10:28.194] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:49:0)
[04:10:28.194] Timer tick, asking Buildbucket for the build status
[04:10:28.395] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:50:0) after 1m0s
[04:11:28.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:50:0)
[04:11:28.644] Timer tick, asking Buildbucket for the build status
[04:11:28.704] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:51:0) after 1m0s
[04:12:28.891] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:51:0)
[04:12:28.891] Timer tick, asking Buildbucket for the build status
[04:12:28.988] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:28.988] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:52:0) after 1m0s
[04:13:29.144] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:52:0)
[04:13:29.144] Timer tick, asking Buildbucket for the build status
[04:13:29.273] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:29.273] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:53:0) after 1m0s
[04:14:29.468] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:53:0)
[04:14:29.468] Timer tick, asking Buildbucket for the build status
[04:14:29.534] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.534] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:54:0) after 1m0s
[04:15:29.627] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:54:0)
[04:15:29.627] Timer tick, asking Buildbucket for the build status
[04:15:29.739] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:55:0) after 1m0s
[04:16:29.830] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:55:0)
[04:16:29.830] Timer tick, asking Buildbucket for the build status
[04:16:30.218] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.218] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:56:0) after 1m0s
[04:17:30.228] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:56:0)
[04:17:30.228] Timer tick, asking Buildbucket for the build status
[04:17:30.321] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.321] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:57:0) after 1m0s
[04:18:30.457] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:57:0)
[04:18:30.457] Timer tick, asking Buildbucket for the build status
[04:18:30.592] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:30.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:58:0) after 1m0s
[04:19:30.593] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:58:0)
[04:19:30.593] Timer tick, asking Buildbucket for the build status
[04:19:30.748] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:30.748] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:59:0) after 1m0s
[04:20:30.755] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:59:0)
[04:20:30.755] Timer tick, asking Buildbucket for the build status
[04:20:31.167] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:31.167] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:60:0) after 1m0s
[04:21:31.329] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:60:0)
[04:21:31.329] Timer tick, asking Buildbucket for the build status
[04:21:31.408] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:61:0) after 1m0s
[04:22:31.515] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:61:0)
[04:22:31.515] Timer tick, asking Buildbucket for the build status
[04:22:31.812] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:31.812] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:62:0) after 1m0s
[04:23:31.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:62:0)
[04:23:31.800] Timer tick, asking Buildbucket for the build status
[04:23:31.904] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:31.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:63:0) after 1m0s
[04:24:32.073] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:63:0)
[04:24:32.073] Timer tick, asking Buildbucket for the build status
[04:24:32.140] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:32.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:64:0) after 1m0s
[04:25:32.252] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:64:0)
[04:25:32.252] Timer tick, asking Buildbucket for the build status
[04:25:32.351] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:32.351] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:65:0) after 1m0s
[04:26:32.435] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:65:0)
[04:26:32.435] Timer tick, asking Buildbucket for the build status
[04:26:32.505] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:32.505] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:66:0) after 1m0s
[04:27:32.538] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:66:0)
[04:27:32.538] Timer tick, asking Buildbucket for the build status
[04:27:32.635] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:32.635] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:67:0) after 1m0s
[04:28:32.703] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:67:0)
[04:28:32.703] Timer tick, asking Buildbucket for the build status
[04:28:33.121] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:33.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:68:0) after 1m0s
[04:29:33.152] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:68:0)
[04:29:33.152] Timer tick, asking Buildbucket for the build status
[04:29:33.298] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:33.298] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:69:0) after 1m0s
[04:30:33.354] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:69:0)
[04:30:33.354] Timer tick, asking Buildbucket for the build status
[04:30:33.449] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:33.449] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:70:0) after 1m0s
[04:31:33.522] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:70:0)
[04:31:33.522] Timer tick, asking Buildbucket for the build status
[04:31:33.604] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:33.604] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:71:0) after 1m0s
[04:32:33.634] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:71:0)
[04:32:33.634] Timer tick, asking Buildbucket for the build status
[04:32:33.700] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:33.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:72:0) after 1m0s
[04:33:33.717] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:72:0)
[04:33:33.717] Timer tick, asking Buildbucket for the build status
[04:33:33.865] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:33.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:73:0) after 1m0s
[04:34:33.984] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:73:0)
[04:34:33.984] Timer tick, asking Buildbucket for the build status
[04:34:34.046] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:34.046] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:74:0) after 1m0s
[04:35:34.090] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:74:0)
[04:35:34.090] Timer tick, asking Buildbucket for the build status
[04:35:34.236] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:34.236] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:75:0) after 1m0s
[04:36:34.263] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:75:0)
[04:36:34.263] Timer tick, asking Buildbucket for the build status
[04:36:34.324] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:34.324] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:76:0) after 1m0s
[04:37:34.406] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:76:0)
[04:37:34.406] Timer tick, asking Buildbucket for the build status
[04:37:34.502] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:34.502] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:77:0) after 1m0s
[04:38:34.576] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:77:0)
[04:38:34.576] Timer tick, asking Buildbucket for the build status
[04:38:34.800] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:34.800] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:78:0) after 1m0s
[04:39:34.836] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:78:0)
[04:39:34.836] Timer tick, asking Buildbucket for the build status
[04:39:34.980] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:34.980] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:79:0) after 1m0s
[04:40:34.959] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:79:0)
[04:40:34.959] Timer tick, asking Buildbucket for the build status
[04:40:35.040] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:35.040] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:80:0) after 1m0s
[04:41:35.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:80:0)
[04:41:35.059] Timer tick, asking Buildbucket for the build status
[04:41:35.140] Build 8870755859235583440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:35.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036185921712739664:81:0) after 1m0s
[04:42:25.971] Received PubSub notification, asking Buildbucket for the build status
[04:42:26.144] Build 8870755859235583440: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:42:26.144] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1598589744677792",
"created_by": "project:chromium",
"created_ts": "1598584992144285",
"failure_reason": "BUILD_FAILURE",
"id": "8870755859235583440",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d4ac1a9f6150a21efa8e6ce2778f8123db60794\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.searchwidget.SearchActivityTest#testVoiceSearchBeforeNativeIsLoaded__EnableRecyclerView\\n\\n- org.chromium.chrome.browser.omnibox.LocationBarLayoutTest#testOmniboxSearchEngineLogo_unfocusedOnSRP\"}, \"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-55-a5sv\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4723, \"got_angle_revision\": \"a1d05e35a865d588a513a7ea6b6668f86919b7d7\", \"got_dawn_revision\": \"900bd341a3c6fc71dfbaeac8adb45b1da0de53d0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"4d4ac1a9f6150a21efa8e6ce2778f8123db60794\", \"got_revision_cp\": \"refs/heads/master@{#802503}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"ec3650f56ca2cc032a46ee4eeb86cccd2b78a6b5\", \"got_v8_revision\": \"1f2195b49c6d7611165ebd149b2825f27693930c\", \"got_v8_revision_cp\": \"refs/heads/8.7.25@{#1}\", \"got_webrtc_revision\": \"3cdde9771d8f289e4701048920ae828dc85e5335\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32009}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d4ac1a9f6150a21efa8e6ce2778f8123db60794\", \"swarm_hashes\": {\"android_browsertests\": \"9a430240a96b12d70fe06b82d152183ae03f103f\", \"chrome_public_test_apk\": \"2c454196c76ef9d691d2abf871da8d9204fe3081\", \"content_browsertests\": \"bcc0d45ec74f4d3ada734a91c5681cfef21dffd2\", \"content_shell_test_apk\": \"79fd7f8c71fe337cc5e8302cfe391d45dfc36208\", \"content_unittests\": \"a693e1a0372ee432eb1ea162e9f1bbb6372c33fc\", \"unit_tests\": \"2069f332ac745cb6476673eded522f6a0b8e94b9\"}, \"swarm_hashes_refs/heads/master(at){#802503}_without_patch\": {\"android_browsertests\": \"9a430240a96b12d70fe06b82d152183ae03f103f\", \"chrome_public_test_apk\": \"2c454196c76ef9d691d2abf871da8d9204fe3081\", \"content_browsertests\": \"bcc0d45ec74f4d3ada734a91c5681cfef21dffd2\", \"content_shell_test_apk\": \"79fd7f8c71fe337cc5e8302cfe391d45dfc36208\", \"content_unittests\": \"a693e1a0372ee432eb1ea162e9f1bbb6372c33fc\", \"unit_tests\": \"2069f332ac745cb6476673eded522f6a0b8e94b9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_49b2c59a41dab71fb2bba5f6f8a4efb56888b0948fbb2a1ad8fa728b25e0dc17_v2\", \"builder_510bb217f1fbd7cfc6f82b4f5538258f70582616cab4052bd10c7cd8994fe33a_v2\", \"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_6337027b9f5c92bb839aef39a1f908f5aaa6ec745f5c7fe76eeae24bfbb4f02b_v2\", \"builder_65593a18063fd659fc9e395a6af5ebbc0164c16c5230228d6fc0d5070f7b5b50_v2\", \"builder_6ee12f05c9a2b0c9a3d47d2261f6d29ee6e08ee8be34f06fc5e9b02ed5b85324_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-55-a5sv\"], \"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.chrome.browser.searchwidget.SearchActivityTest#testVoiceSearchBeforeNativeIsLoaded__EnableRecyclerView\\n\\n- org.chromium.chrome.browser.omnibox.LocationBarLayoutTest#testOmniboxSearchEngineLogo_unfocusedOnSRP\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598585173044129",
"status": "COMPLETED",
"status_changed_ts": "1598585173512711",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4723",
"builder:android-bfcache-rel",
"buildset:commit/git/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d4ac1a9f6150a21efa8e6ce2778f8123db60794",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036185921712739664",
"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/8870755859235583440/+/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:4e4be8bc1665e210",
"user_agent:luci-scheduler"
],
"updated_ts": "1598589745490112",
"url": "https://ci.chromium.org/b/8870755859235583440",
"utcnow_ts": "1598589746134368"
}
}
[04:42:26.144] Invocation finished in 1h19m15.746403588s with status FAILED