[02:23:14.167] New invocation is queued and will start shortly
[02:23:15.379] Starting the invocation (attempt 1)
[02:23:15.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:23:15.418] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:23:15.418] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9043346852998653984",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f055cc561ed908df675699054cd6d6a941942379\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9043346852998653984",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f055cc561ed908df675699054cd6d6a941942379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f055cc561ed908df675699054cd6d6a941942379",
"gitiles_ref:refs/heads/master"
]
}
[02:23:16.262] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1591755795518990",
"id": "8877916790917637824",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f055cc561ed908df675699054cd6d6a941942379\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591755796132575",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3236",
"builder:android-bfcache-rel",
"buildset:commit/git/f055cc561ed908df675699054cd6d6a941942379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f055cc561ed908df675699054cd6d6a941942379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043346852998653984",
"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/8877916790917637824/+/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": "1591755796132721",
"url": "https://ci.chromium.org/b/8877916790917637824",
"utcnow_ts": "1591755796253048"
}
}
[02:23:16.262] Task URL: https://ci.chromium.org/b/8877916790917637824
[02:23:16.262] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:2:0) after 1m0s
[02:23:40.555] Received PubSub notification, asking Buildbucket for the build status
[02:23:40.736] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.301] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:2:0)
[02:24:16.301] Timer tick, asking Buildbucket for the build status
[02:24:16.645] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.646] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:4:0) after 1m0s
[02:25:16.743] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:4:0)
[02:25:16.743] Timer tick, asking Buildbucket for the build status
[02:25:17.055] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:17.055] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:5:0) after 1m0s
[02:26:17.078] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:5:0)
[02:26:17.078] Timer tick, asking Buildbucket for the build status
[02:26:17.281] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:17.281] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:6:0) after 1m0s
[02:27:17.313] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:6:0)
[02:27:17.313] Timer tick, asking Buildbucket for the build status
[02:27:17.636] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:17.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:7:0) after 1m0s
[02:28:17.641] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:7:0)
[02:28:17.641] Timer tick, asking Buildbucket for the build status
[02:28:18.045] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:18.045] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:8:0) after 1m0s
[02:29:18.099] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:8:0)
[02:29:18.099] Timer tick, asking Buildbucket for the build status
[02:29:18.262] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:18.262] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:9:0) after 1m0s
[02:30:18.257] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:9:0)
[02:30:18.257] Timer tick, asking Buildbucket for the build status
[02:30:18.362] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:18.362] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:10:0) after 1m0s
[02:31:18.408] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:10:0)
[02:31:18.408] Timer tick, asking Buildbucket for the build status
[02:31:18.672] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:18.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:11:0) after 1m0s
[02:32:18.695] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:11:0)
[02:32:18.695] Timer tick, asking Buildbucket for the build status
[02:32:19.380] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:19.380] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:12:0) after 1m0s
[02:33:19.398] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:12:0)
[02:33:19.398] Timer tick, asking Buildbucket for the build status
[02:33:19.639] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:19.639] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:13:0) after 1m0s
[02:34:19.730] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:13:0)
[02:34:19.730] Timer tick, asking Buildbucket for the build status
[02:34:19.914] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:19.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:14:0) after 1m0s
[02:35:19.890] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:14:0)
[02:35:19.890] Timer tick, asking Buildbucket for the build status
[02:35:20.033] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:20.033] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:15:0) after 1m0s
[02:36:20.013] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:15:0)
[02:36:20.013] Timer tick, asking Buildbucket for the build status
[02:36:20.392] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:16:0) after 1m0s
[02:37:20.413] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:16:0)
[02:37:20.413] Timer tick, asking Buildbucket for the build status
[02:37:20.559] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:20.559] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:17:0) after 1m0s
[02:38:20.600] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:17:0)
[02:38:20.600] Timer tick, asking Buildbucket for the build status
[02:38:20.714] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:20.714] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:18:0) after 1m0s
[02:39:20.766] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:18:0)
[02:39:20.766] Timer tick, asking Buildbucket for the build status
[02:39:23.919] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:19:0) after 1m0s
[02:40:23.939] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:19:0)
[02:40:23.939] Timer tick, asking Buildbucket for the build status
[02:40:24.118] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:24.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:20:0) after 1m0s
[02:41:24.143] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:20:0)
[02:41:24.143] Timer tick, asking Buildbucket for the build status
[02:41:24.602] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:24.602] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:21:0) after 1m0s
[02:42:24.630] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:21:0)
[02:42:24.630] Timer tick, asking Buildbucket for the build status
[02:42:24.994] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:24.994] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:22:0) after 1m0s
[02:43:25.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:22:0)
[02:43:25.036] Timer tick, asking Buildbucket for the build status
[02:43:25.163] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:25.163] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:23:0) after 1m0s
[02:44:25.220] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:23:0)
[02:44:25.220] Timer tick, asking Buildbucket for the build status
[02:44:25.527] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:25.527] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:24:0) after 1m0s
[02:45:25.606] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:24:0)
[02:45:25.606] Timer tick, asking Buildbucket for the build status
[02:45:26.301] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:26.301] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:25:0) after 1m0s
[02:46:26.351] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:25:0)
[02:46:26.351] Timer tick, asking Buildbucket for the build status
[02:46:26.771] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:26.771] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:26:0) after 1m0s
[02:47:26.840] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:26:0)
[02:47:26.840] Timer tick, asking Buildbucket for the build status
[02:47:27.167] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:27.168] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:27:0) after 1m0s
[02:48:27.198] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:27:0)
[02:48:27.198] Timer tick, asking Buildbucket for the build status
[02:48:27.314] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:28:0) after 1m0s
[02:49:27.336] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:28:0)
[02:49:27.336] Timer tick, asking Buildbucket for the build status
[02:49:27.757] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:27.757] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:29:0) after 1m0s
[02:50:27.790] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:29:0)
[02:50:27.790] Timer tick, asking Buildbucket for the build status
[02:50:28.134] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:28.134] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:30:0) after 1m0s
[02:51:28.187] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:30:0)
[02:51:28.187] Timer tick, asking Buildbucket for the build status
[02:51:28.878] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:28.878] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:31:0) after 1m0s
[02:52:28.909] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:31:0)
[02:52:28.909] Timer tick, asking Buildbucket for the build status
[02:52:29.118] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:29.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:32:0) after 1m0s
[02:53:29.166] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:32:0)
[02:53:29.166] Timer tick, asking Buildbucket for the build status
[02:53:29.346] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:29.346] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:33:0) after 1m0s
[02:54:29.369] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:33:0)
[02:54:29.369] Timer tick, asking Buildbucket for the build status
[02:54:29.720] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:29.720] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:34:0) after 1m0s
[02:55:29.778] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:34:0)
[02:55:29.778] Timer tick, asking Buildbucket for the build status
[02:55:30.105] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:30.105] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:35:0) after 1m0s
[02:56:30.123] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:35:0)
[02:56:30.123] Timer tick, asking Buildbucket for the build status
[02:56:30.545] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:36:0) after 1m0s
[02:57:30.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:36:0)
[02:57:30.565] Timer tick, asking Buildbucket for the build status
[02:57:30.677] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:30.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:37:0) after 1m0s
[02:58:30.714] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:37:0)
[02:58:30.714] Timer tick, asking Buildbucket for the build status
[02:58:30.852] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:30.852] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:38:0) after 1m0s
[02:59:30.837] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:38:0)
[02:59:30.837] Timer tick, asking Buildbucket for the build status
[02:59:30.957] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:30.957] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:39:0) after 1m0s
[03:00:30.980] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:39:0)
[03:00:30.980] Timer tick, asking Buildbucket for the build status
[03:00:31.109] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:40:0) after 1m0s
[03:01:31.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:40:0)
[03:01:31.239] Timer tick, asking Buildbucket for the build status
[03:01:31.881] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:31.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:41:0) after 1m0s
[03:02:31.913] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:41:0)
[03:02:31.913] Timer tick, asking Buildbucket for the build status
[03:02:32.047] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:32.047] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:42:0) after 1m0s
[03:03:32.123] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:42:0)
[03:03:32.123] Timer tick, asking Buildbucket for the build status
[03:03:33.326] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:33.326] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:43:0) after 1m0s
[03:04:33.351] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:43:0)
[03:04:33.351] Timer tick, asking Buildbucket for the build status
[03:04:33.444] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:33.444] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:44:0) after 1m0s
[03:05:33.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:44:0)
[03:05:33.567] Timer tick, asking Buildbucket for the build status
[03:05:33.885] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:33.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:45:0) after 1m0s
[03:06:33.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:45:0)
[03:06:33.943] Timer tick, asking Buildbucket for the build status
[03:06:34.181] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:34.181] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:46:0) after 1m0s
[03:07:34.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:46:0)
[03:07:34.639] Timer tick, asking Buildbucket for the build status
[03:07:34.763] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:34.763] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:47:0) after 1m0s
[03:08:34.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:47:0)
[03:08:34.941] Timer tick, asking Buildbucket for the build status
[03:08:35.592] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:35.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:48:0) after 1m0s
[03:09:35.657] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:48:0)
[03:09:35.657] Timer tick, asking Buildbucket for the build status
[03:09:35.844] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:35.844] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:49:0) after 1m0s
[03:10:35.964] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:49:0)
[03:10:35.964] Timer tick, asking Buildbucket for the build status
[03:10:36.369] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:36.369] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:50:0) after 1m0s
[03:11:36.401] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:50:0)
[03:11:36.401] Timer tick, asking Buildbucket for the build status
[03:11:40.291] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:40.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:51:0) after 1m0s
[03:12:40.365] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:51:0)
[03:12:40.365] Timer tick, asking Buildbucket for the build status
[03:12:40.621] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:40.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:52:0) after 1m0s
[03:13:40.640] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:52:0)
[03:13:40.640] Timer tick, asking Buildbucket for the build status
[03:13:40.843] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:40.843] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:53:0) after 1m0s
[03:14:40.903] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:53:0)
[03:14:40.903] Timer tick, asking Buildbucket for the build status
[03:14:41.118] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:41.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:54:0) after 1m0s
[03:15:41.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:54:0)
[03:15:41.153] Timer tick, asking Buildbucket for the build status
[03:15:41.289] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:41.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:55:0) after 1m0s
[03:16:41.344] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:55:0)
[03:16:41.344] Timer tick, asking Buildbucket for the build status
[03:16:43.132] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:43.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:56:0) after 1m0s
[03:17:43.199] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:56:0)
[03:17:43.199] Timer tick, asking Buildbucket for the build status
[03:17:43.340] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:43.340] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:57:0) after 1m0s
[03:18:43.373] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:57:0)
[03:18:43.373] Timer tick, asking Buildbucket for the build status
[03:18:43.712] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:43.712] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:58:0) after 1m0s
[03:19:43.838] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:58:0)
[03:19:43.838] Timer tick, asking Buildbucket for the build status
[03:19:44.284] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:44.284] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:59:0) after 1m0s
[03:20:44.340] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:59:0)
[03:20:44.340] Timer tick, asking Buildbucket for the build status
[03:20:44.863] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:44.863] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:60:0) after 1m0s
[03:21:44.863] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:60:0)
[03:21:44.863] Timer tick, asking Buildbucket for the build status
[03:21:45.122] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:45.122] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:61:0) after 1m0s
[03:22:45.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:61:0)
[03:22:45.437] Timer tick, asking Buildbucket for the build status
[03:22:45.539] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:45.539] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:62:0) after 1m0s
[03:23:45.585] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:62:0)
[03:23:45.585] Timer tick, asking Buildbucket for the build status
[03:23:45.859] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:45.860] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:63:0) after 1m0s
[03:24:45.962] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:63:0)
[03:24:45.962] Timer tick, asking Buildbucket for the build status
[03:24:46.089] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:46.089] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:64:0) after 1m0s
[03:25:46.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:64:0)
[03:25:46.239] Timer tick, asking Buildbucket for the build status
[03:25:47.560] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:65:0) after 1m0s
[03:26:47.691] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:65:0)
[03:26:47.691] Timer tick, asking Buildbucket for the build status
[03:26:47.973] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:47.973] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:66:0) after 1m0s
[03:27:48.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:66:0)
[03:27:48.153] Timer tick, asking Buildbucket for the build status
[03:27:48.316] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:48.316] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:67:0) after 1m0s
[03:28:48.429] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:67:0)
[03:28:48.429] Timer tick, asking Buildbucket for the build status
[03:28:48.648] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:48.648] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:68:0) after 1m0s
[03:29:48.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:68:0)
[03:29:48.749] Timer tick, asking Buildbucket for the build status
[03:29:48.869] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:48.869] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:69:0) after 1m0s
[03:30:48.977] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:69:0)
[03:30:48.977] Timer tick, asking Buildbucket for the build status
[03:30:49.156] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:49.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:70:0) after 1m0s
[03:31:49.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:70:0)
[03:31:49.208] Timer tick, asking Buildbucket for the build status
[03:31:49.344] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:49.344] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:71:0) after 1m0s
[03:32:49.407] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:71:0)
[03:32:49.407] Timer tick, asking Buildbucket for the build status
[03:32:49.557] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:49.557] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:72:0) after 1m0s
[03:33:49.615] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:72:0)
[03:33:49.615] Timer tick, asking Buildbucket for the build status
[03:33:49.888] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:49.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:73:0) after 1m0s
[03:34:49.925] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:73:0)
[03:34:49.925] Timer tick, asking Buildbucket for the build status
[03:34:50.067] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:50.067] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:74:0) after 1m0s
[03:35:50.107] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:74:0)
[03:35:50.107] Timer tick, asking Buildbucket for the build status
[03:35:50.276] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:50.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:75:0) after 1m0s
[03:36:50.381] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:75:0)
[03:36:50.381] Timer tick, asking Buildbucket for the build status
[03:36:50.647] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:50.647] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:76:0) after 1m0s
[03:37:50.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:76:0)
[03:37:50.706] Timer tick, asking Buildbucket for the build status
[03:37:50.836] Build 8877916790917637824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:50.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9043346852998653984:77:0) after 1m0s
[03:38:30.515] Received PubSub notification, asking Buildbucket for the build status
[03:38:30.649] Build 8877916790917637824: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:38:30.649] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1591760310051546",
"created_by": "project:chromium",
"created_ts": "1591755795518990",
"failure_reason": "BUILD_FAILURE",
"id": "8877916790917637824",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f055cc561ed908df675699054cd6d6a941942379\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- All/RenderFrameHostManagerDefaultProcessTest.NavigationRacesWithSitelessCommitInDefaultProcess/1\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- All/RenderFrameHostManagerTest.ProactivelySwapBrowsingInstance/0\\n\\n- ...24 more failure(s) (28 total)...\\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__NoInstant_NoReturn\\n\\n- ...121 more failure(s) (124 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-central1-b-8-33-ppcw\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 3236, \"got_angle_revision\": \"d80d9044857e168bb7945eb726a451c20140b80c\", \"got_dawn_revision\": \"14f20b0ab38d5996d2c2716156912491783d93fa\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"f055cc561ed908df675699054cd6d6a941942379\", \"got_revision_cp\": \"refs/heads/master@{#776806}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e3eb327e8c3c724a415fa4d5f4805f2e281f4760\", \"got_v8_revision\": \"9912e35d594bf00cd78697889beff6b238ebe66d\", \"got_v8_revision_cp\": \"refs/heads/8.5.133@{#1}\", \"got_webrtc_revision\": \"0c3062efcb3b001bec0dfacbcaa15e5b73c21a64\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31477}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f055cc561ed908df675699054cd6d6a941942379\", \"swarm_hashes\": {\"android_browsertests\": \"0c4e8b59754161431d85f4d213eb563414676aab\", \"chrome_public_test_apk\": \"ec742029265554978f078338dead35015167b329\", \"content_browsertests\": \"80aa3e9bf3289b55a598988ae11b1b28f83c768f\", \"content_shell_test_apk\": \"4832a427ad595f53e6a3d0bd27ad6e7f55acc288\", \"content_unittests\": \"eacb5c8c18c02bc7115b5dd0070278a8a33c07b7\", \"unit_tests\": \"311f15e69dae801d47ed23967c704ef3f10ab7d6\"}, \"swarm_hashes_refs/heads/master(at){#776806}_without_patch\": {\"android_browsertests\": \"0c4e8b59754161431d85f4d213eb563414676aab\", \"chrome_public_test_apk\": \"ec742029265554978f078338dead35015167b329\", \"content_browsertests\": \"80aa3e9bf3289b55a598988ae11b1b28f83c768f\", \"content_shell_test_apk\": \"4832a427ad595f53e6a3d0bd27ad6e7f55acc288\", \"content_unittests\": \"eacb5c8c18c02bc7115b5dd0070278a8a33c07b7\", \"unit_tests\": \"311f15e69dae801d47ed23967c704ef3f10ab7d6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0ab30d842ffd941c611816ba2c4af11dd26844235ea2fddaccb8ab24692950db_v2\", \"builder_392e4b5085eb0dbce6a99d938b7f12909ff6ee2b2bba17d3de3954de60592c3b_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_bdb70a5d31beeee95e43e31d29f49b3af55c56759f7dba469ec655f2f99557ab_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_d657915880dce23b8aa739d6ce38296aa330af834de88e5cf3e9ccfc5db7a6fa_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-33-ppcw\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5190-b7dcd03\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- All/RenderFrameHostManagerDefaultProcessTest.NavigationRacesWithSitelessCommitInDefaultProcess/1\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- All/RenderFrameHostManagerTest.ProactivelySwapBrowsingInstance/0\\n\\n- ...24 more failure(s) (28 total)...\\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__NoInstant_NoReturn\\n\\n- ...121 more failure(s) (124 total)...\\n\\n#### ...1 more test(s)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1591755819884175",
"status": "COMPLETED",
"status_changed_ts": "1591755820287264",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3236",
"builder:android-bfcache-rel",
"buildset:commit/git/f055cc561ed908df675699054cd6d6a941942379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f055cc561ed908df675699054cd6d6a941942379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043346852998653984",
"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/8877916790917637824/+/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:4cb4db78a692bb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1591760310187035",
"url": "https://ci.chromium.org/b/8877916790917637824",
"utcnow_ts": "1591760310634342"
}
}
[03:38:30.649] Invocation finished in 1h15m16.499608266s with status FAILED