[21:20:25.439] New invocation is queued and will start shortly
[21:20:26.679] Starting the invocation (attempt 1)
[21:20:26.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:20:26.728] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:20:26.728] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034034416753918000",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"355d28745436a638c0525cb92f55684375d5edb2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9034034416753918000",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/355d28745436a638c0525cb92f55684375d5edb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/355d28745436a638c0525cb92f55684375d5edb2",
"gitiles_ref:refs/heads/master"
]
}
[21:20:27.525] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600636826838780",
"id": "8868604354619966016",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"355d28745436a638c0525cb92f55684375d5edb2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600636827325103",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5134",
"builder:android-bfcache-rel",
"buildset:commit/git/355d28745436a638c0525cb92f55684375d5edb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/355d28745436a638c0525cb92f55684375d5edb2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034034416753918000",
"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/8868604354619966016/+/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": "1600636827325212",
"url": "https://ci.chromium.org/b/8868604354619966016",
"utcnow_ts": "1600636827515968"
}
}
[21:20:27.525] Task URL: https://ci.chromium.org/b/8868604354619966016
[21:20:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:2:0) after 1m0s
[21:21:27.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:2:0)
[21:21:27.603] Timer tick, asking Buildbucket for the build status
[21:21:27.735] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:27.735] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:3:0) after 1m0s
[21:22:27.762] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:3:0)
[21:22:27.762] Timer tick, asking Buildbucket for the build status
[21:22:27.961] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:27.961] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:4:0) after 1m0s
[21:23:27.980] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:4:0)
[21:23:27.980] Timer tick, asking Buildbucket for the build status
[21:23:28.284] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:28.284] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:5:0) after 1m0s
[21:24:28.293] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:5:0)
[21:24:28.293] Timer tick, asking Buildbucket for the build status
[21:24:28.408] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:28.408] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:6:0) after 1m0s
[21:25:28.471] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:6:0)
[21:25:28.471] Timer tick, asking Buildbucket for the build status
[21:25:28.570] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:28.571] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:7:0) after 1m0s
[21:26:28.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:7:0)
[21:26:28.603] Timer tick, asking Buildbucket for the build status
[21:26:28.818] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:28.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:8:0) after 1m0s
[21:27:28.914] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:8:0)
[21:27:28.914] Timer tick, asking Buildbucket for the build status
[21:27:28.991] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:28.991] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:9:0) after 1m0s
[21:28:29.054] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:9:0)
[21:28:29.054] Timer tick, asking Buildbucket for the build status
[21:28:29.297] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:29.297] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:10:0) after 1m0s
[21:29:29.320] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:10:0)
[21:29:29.320] Timer tick, asking Buildbucket for the build status
[21:29:29.414] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:29.414] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:11:0) after 1m0s
[21:30:29.504] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:11:0)
[21:30:29.504] Timer tick, asking Buildbucket for the build status
[21:30:29.624] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:29.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:12:0) after 1m0s
[21:31:29.662] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:12:0)
[21:31:29.662] Timer tick, asking Buildbucket for the build status
[21:31:29.738] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:29.738] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:13:0) after 1m0s
[21:32:29.763] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:13:0)
[21:32:29.763] Timer tick, asking Buildbucket for the build status
[21:32:30.114] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:30.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:14:0) after 1m0s
[21:33:30.192] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:14:0)
[21:33:30.192] Timer tick, asking Buildbucket for the build status
[21:33:30.284] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:30.284] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:15:0) after 1m0s
[21:34:30.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:15:0)
[21:34:30.306] Timer tick, asking Buildbucket for the build status
[21:34:30.391] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:30.391] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:16:0) after 1m0s
[21:35:30.468] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:16:0)
[21:35:30.468] Timer tick, asking Buildbucket for the build status
[21:35:30.701] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:30.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:17:0) after 1m0s
[21:36:30.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:17:0)
[21:36:30.745] Timer tick, asking Buildbucket for the build status
[21:36:30.854] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:18:0) after 1m0s
[21:37:30.884] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:18:0)
[21:37:30.884] Timer tick, asking Buildbucket for the build status
[21:37:30.990] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:30.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:19:0) after 1m0s
[21:38:31.023] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:19:0)
[21:38:31.023] Timer tick, asking Buildbucket for the build status
[21:38:31.215] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:31.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:20:0) after 1m0s
[21:39:31.187] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:20:0)
[21:39:31.187] Timer tick, asking Buildbucket for the build status
[21:39:31.270] Build 8868604354619966016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:31.270] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:21:0) after 1m0s
[21:40:25.859] Received PubSub notification, asking Buildbucket for the build status
[21:40:25.943] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:33.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:21:0)
[21:40:33.978] Timer tick, asking Buildbucket for the build status
[21:40:34.083] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:34.083] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:23:0) after 1m0s
[21:41:34.272] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:23:0)
[21:41:34.272] Timer tick, asking Buildbucket for the build status
[21:41:34.349] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:34.349] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:24:0) after 1m0s
[21:42:34.802] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:24:0)
[21:42:34.802] Timer tick, asking Buildbucket for the build status
[21:42:34.916] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:34.916] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:25:0) after 1m0s
[21:43:35.163] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:25:0)
[21:43:35.163] Timer tick, asking Buildbucket for the build status
[21:43:35.289] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:35.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:26:0) after 1m0s
[21:44:35.638] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:26:0)
[21:44:35.638] Timer tick, asking Buildbucket for the build status
[21:44:35.942] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:27:0) after 1m0s
[21:45:36.112] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:27:0)
[21:45:36.112] Timer tick, asking Buildbucket for the build status
[21:45:36.579] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:36.579] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:28:0) after 1m0s
[21:46:37.144] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:28:0)
[21:46:37.144] Timer tick, asking Buildbucket for the build status
[21:46:37.430] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:37.430] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:29:0) after 1m0s
[21:47:37.710] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:29:0)
[21:47:37.710] Timer tick, asking Buildbucket for the build status
[21:47:37.822] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:37.822] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:30:0) after 1m0s
[21:48:37.980] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:30:0)
[21:48:37.980] Timer tick, asking Buildbucket for the build status
[21:48:38.040] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:38.040] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:31:0) after 1m0s
[21:49:40.314] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:31:0)
[21:49:40.314] Timer tick, asking Buildbucket for the build status
[21:49:40.426] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:32:0) after 1m0s
[21:50:40.832] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:32:0)
[21:50:40.832] Timer tick, asking Buildbucket for the build status
[21:50:40.973] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:40.973] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:33:0) after 1m0s
[21:51:41.210] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:33:0)
[21:51:41.210] Timer tick, asking Buildbucket for the build status
[21:51:41.396] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:41.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:34:0) after 1m0s
[21:52:41.607] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:34:0)
[21:52:41.607] Timer tick, asking Buildbucket for the build status
[21:52:41.672] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:41.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:35:0) after 1m0s
[21:53:41.642] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:35:0)
[21:53:41.642] Timer tick, asking Buildbucket for the build status
[21:53:41.715] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:41.715] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:36:0) after 1m0s
[21:54:41.730] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:36:0)
[21:54:41.730] Timer tick, asking Buildbucket for the build status
[21:54:41.795] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:41.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:37:0) after 1m0s
[21:55:41.844] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:37:0)
[21:55:41.845] Timer tick, asking Buildbucket for the build status
[21:55:41.977] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:41.977] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:38:0) after 1m0s
[21:56:42.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:38:0)
[21:56:42.034] Timer tick, asking Buildbucket for the build status
[21:56:42.202] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:42.202] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:39:0) after 1m0s
[21:57:42.266] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:39:0)
[21:57:42.266] Timer tick, asking Buildbucket for the build status
[21:57:42.392] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:42.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:40:0) after 1m0s
[21:58:42.449] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:40:0)
[21:58:42.449] Timer tick, asking Buildbucket for the build status
[21:58:42.525] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:42.525] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:41:0) after 1m0s
[21:59:42.582] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:41:0)
[21:59:42.582] Timer tick, asking Buildbucket for the build status
[21:59:42.661] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:42.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:42:0) after 1m0s
[22:00:42.676] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:42:0)
[22:00:42.676] Timer tick, asking Buildbucket for the build status
[22:00:42.817] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:42.817] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:43:0) after 1m0s
[22:01:42.839] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:43:0)
[22:01:42.839] Timer tick, asking Buildbucket for the build status
[22:01:43.206] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:44:0) after 1m0s
[22:02:43.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:44:0)
[22:02:43.239] Timer tick, asking Buildbucket for the build status
[22:02:43.314] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:43.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:45:0) after 1m0s
[22:03:43.621] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:45:0)
[22:03:43.622] Timer tick, asking Buildbucket for the build status
[22:03:43.731] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:43.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:46:0) after 1m0s
[22:04:43.841] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:46:0)
[22:04:43.841] Timer tick, asking Buildbucket for the build status
[22:04:44.029] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:44.029] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:47:0) after 1m0s
[22:05:44.190] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:47:0)
[22:05:44.190] Timer tick, asking Buildbucket for the build status
[22:05:44.562] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:44.562] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:48:0) after 1m0s
[22:06:44.888] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:48:0)
[22:06:44.888] Timer tick, asking Buildbucket for the build status
[22:06:45.106] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:45.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:49:0) after 1m0s
[22:07:45.284] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:49:0)
[22:07:45.284] Timer tick, asking Buildbucket for the build status
[22:07:45.819] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:45.819] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:50:0) after 1m0s
[22:08:45.905] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:50:0)
[22:08:45.905] Timer tick, asking Buildbucket for the build status
[22:08:46.009] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:46.009] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:51:0) after 1m0s
[22:09:46.130] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:51:0)
[22:09:46.130] Timer tick, asking Buildbucket for the build status
[22:09:46.187] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:46.187] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:52:0) after 1m0s
[22:10:46.319] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:52:0)
[22:10:46.319] Timer tick, asking Buildbucket for the build status
[22:10:46.471] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:46.471] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:53:0) after 1m0s
[22:11:46.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:53:0)
[22:11:46.597] Timer tick, asking Buildbucket for the build status
[22:11:46.671] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:46.671] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:54:0) after 1m0s
[22:12:46.756] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:54:0)
[22:12:46.756] Timer tick, asking Buildbucket for the build status
[22:12:46.887] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:46.887] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:55:0) after 1m0s
[22:13:46.907] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:55:0)
[22:13:46.907] Timer tick, asking Buildbucket for the build status
[22:13:46.990] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:46.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:56:0) after 1m0s
[22:14:47.019] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:56:0)
[22:14:47.019] Timer tick, asking Buildbucket for the build status
[22:14:47.317] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:47.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:57:0) after 1m0s
[22:15:47.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:57:0)
[22:15:47.375] Timer tick, asking Buildbucket for the build status
[22:15:47.489] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:47.489] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:58:0) after 1m0s
[22:16:47.478] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:58:0)
[22:16:47.478] Timer tick, asking Buildbucket for the build status
[22:16:47.577] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:47.577] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:59:0) after 1m0s
[22:17:47.631] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:59:0)
[22:17:47.631] Timer tick, asking Buildbucket for the build status
[22:17:47.913] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:47.913] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:60:0) after 1m0s
[22:18:47.986] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:60:0)
[22:18:47.986] Timer tick, asking Buildbucket for the build status
[22:18:48.069] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:48.069] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:61:0) after 1m0s
[22:19:48.069] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:61:0)
[22:19:48.069] Timer tick, asking Buildbucket for the build status
[22:19:48.146] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:48.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:62:0) after 1m0s
[22:20:48.197] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:62:0)
[22:20:48.197] Timer tick, asking Buildbucket for the build status
[22:20:48.324] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:48.324] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:63:0) after 1m0s
[22:21:48.445] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:63:0)
[22:21:48.445] Timer tick, asking Buildbucket for the build status
[22:21:48.531] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:48.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:64:0) after 1m0s
[22:22:48.578] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:64:0)
[22:22:48.579] Timer tick, asking Buildbucket for the build status
[22:22:48.752] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:48.752] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:65:0) after 1m0s
[22:23:48.865] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:65:0)
[22:23:48.865] Timer tick, asking Buildbucket for the build status
[22:23:49.011] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:49.011] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:66:0) after 1m0s
[22:24:49.126] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:66:0)
[22:24:49.127] Timer tick, asking Buildbucket for the build status
[22:24:49.191] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:49.191] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:67:0) after 1m0s
[22:25:49.198] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:67:0)
[22:25:49.198] Timer tick, asking Buildbucket for the build status
[22:25:49.339] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:49.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:68:0) after 1m0s
[22:26:49.521] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:68:0)
[22:26:49.521] Timer tick, asking Buildbucket for the build status
[22:26:49.617] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:49.617] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:69:0) after 1m0s
[22:27:49.862] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:69:0)
[22:27:49.862] Timer tick, asking Buildbucket for the build status
[22:27:50.126] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:50.126] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:70:0) after 1m0s
[22:28:50.226] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:70:0)
[22:28:50.226] Timer tick, asking Buildbucket for the build status
[22:28:50.327] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:50.327] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:71:0) after 1m0s
[22:29:50.429] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:71:0)
[22:29:50.429] Timer tick, asking Buildbucket for the build status
[22:29:50.624] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:50.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:72:0) after 1m0s
[22:30:50.769] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:72:0)
[22:30:50.769] Timer tick, asking Buildbucket for the build status
[22:30:50.912] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:50.912] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:73:0) after 1m0s
[22:31:51.114] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:73:0)
[22:31:51.114] Timer tick, asking Buildbucket for the build status
[22:31:51.191] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:51.191] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:74:0) after 1m0s
[22:32:51.334] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:74:0)
[22:32:51.334] Timer tick, asking Buildbucket for the build status
[22:32:51.446] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:51.446] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:75:0) after 1m0s
[22:33:51.475] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:75:0)
[22:33:51.475] Timer tick, asking Buildbucket for the build status
[22:33:51.721] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:51.721] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:76:0) after 1m0s
[22:34:51.789] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:76:0)
[22:34:51.789] Timer tick, asking Buildbucket for the build status
[22:34:51.851] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:77:0) after 1m0s
[22:35:51.826] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:77:0)
[22:35:51.826] Timer tick, asking Buildbucket for the build status
[22:35:51.944] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:51.944] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:78:0) after 1m0s
[22:36:51.987] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:78:0)
[22:36:51.987] Timer tick, asking Buildbucket for the build status
[22:36:52.052] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:52.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:79:0) after 1m0s
[22:37:52.106] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:79:0)
[22:37:52.106] Timer tick, asking Buildbucket for the build status
[22:37:52.417] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:52.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:80:0) after 1m0s
[22:38:52.483] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:80:0)
[22:38:52.483] Timer tick, asking Buildbucket for the build status
[22:38:52.658] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:52.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:81:0) after 1m0s
[22:39:52.686] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:81:0)
[22:39:52.686] Timer tick, asking Buildbucket for the build status
[22:39:52.809] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:52.809] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:82:0) after 1m0s
[22:40:52.872] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:82:0)
[22:40:52.872] Timer tick, asking Buildbucket for the build status
[22:40:53.076] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:53.076] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:83:0) after 1m0s
[22:41:53.065] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:83:0)
[22:41:53.065] Timer tick, asking Buildbucket for the build status
[22:41:53.120] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:53.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:84:0) after 1m0s
[22:42:53.161] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:84:0)
[22:42:53.161] Timer tick, asking Buildbucket for the build status
[22:42:53.235] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:53.235] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:85:0) after 1m0s
[22:43:53.464] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:85:0)
[22:43:53.464] Timer tick, asking Buildbucket for the build status
[22:43:53.556] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:53.556] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:86:0) after 1m0s
[22:44:53.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:86:0)
[22:44:53.609] Timer tick, asking Buildbucket for the build status
[22:44:53.699] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:53.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:87:0) after 1m0s
[22:45:53.725] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:87:0)
[22:45:53.725] Timer tick, asking Buildbucket for the build status
[22:45:53.849] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:53.849] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:88:0) after 1m0s
[22:46:53.899] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:88:0)
[22:46:53.899] Timer tick, asking Buildbucket for the build status
[22:46:53.952] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:53.952] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:89:0) after 1m0s
[22:47:54.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:89:0)
[22:47:54.036] Timer tick, asking Buildbucket for the build status
[22:47:54.202] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:90:0) after 1m0s
[22:48:54.271] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:90:0)
[22:48:54.271] Timer tick, asking Buildbucket for the build status
[22:48:55.032] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:55.032] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:91:0) after 1m0s
[22:49:55.072] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:91:0)
[22:49:55.072] Timer tick, asking Buildbucket for the build status
[22:49:55.141] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:55.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:92:0) after 1m0s
[22:50:55.169] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:92:0)
[22:50:55.169] Timer tick, asking Buildbucket for the build status
[22:50:55.397] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:55.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:93:0) after 1m0s
[22:51:55.429] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:93:0)
[22:51:55.429] Timer tick, asking Buildbucket for the build status
[22:51:55.692] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:55.692] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:94:0) after 1m0s
[22:52:55.717] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:94:0)
[22:52:55.717] Timer tick, asking Buildbucket for the build status
[22:52:55.883] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:55.883] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:95:0) after 1m0s
[22:53:55.957] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:95:0)
[22:53:55.957] Timer tick, asking Buildbucket for the build status
[22:53:56.030] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:56.030] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:96:0) after 1m0s
[22:54:56.069] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:96:0)
[22:54:56.069] Timer tick, asking Buildbucket for the build status
[22:54:56.276] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:56.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:97:0) after 1m0s
[22:55:56.381] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:97:0)
[22:55:56.381] Timer tick, asking Buildbucket for the build status
[22:55:56.920] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:56.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:98:0) after 1m0s
[22:56:56.972] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:98:0)
[22:56:56.972] Timer tick, asking Buildbucket for the build status
[22:56:57.052] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:57.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:99:0) after 1m0s
[22:57:57.176] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:99:0)
[22:57:57.176] Timer tick, asking Buildbucket for the build status
[22:57:57.339] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:57.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:100:0) after 1m0s
[22:58:57.380] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:100:0)
[22:58:57.380] Timer tick, asking Buildbucket for the build status
[22:58:57.607] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:57.607] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:101:0) after 1m0s
[22:59:57.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:101:0)
[22:59:57.639] Timer tick, asking Buildbucket for the build status
[22:59:58.026] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:58.026] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:102:0) after 1m0s
[23:00:58.060] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:102:0)
[23:00:58.060] Timer tick, asking Buildbucket for the build status
[23:00:58.171] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:58.171] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:103:0) after 1m0s
[23:01:58.246] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:103:0)
[23:01:58.246] Timer tick, asking Buildbucket for the build status
[23:01:58.458] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:58.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:104:0) after 1m0s
[23:02:58.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:104:0)
[23:02:58.527] Timer tick, asking Buildbucket for the build status
[23:02:58.664] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:58.664] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:105:0) after 1m0s
[23:03:58.711] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:105:0)
[23:03:58.711] Timer tick, asking Buildbucket for the build status
[23:03:58.881] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:58.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:106:0) after 1m0s
[23:04:58.924] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:106:0)
[23:04:58.924] Timer tick, asking Buildbucket for the build status
[23:04:59.048] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:59.048] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:107:0) after 1m0s
[23:05:59.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:107:0)
[23:05:59.083] Timer tick, asking Buildbucket for the build status
[23:05:59.215] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:59.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:108:0) after 1m0s
[23:06:59.263] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:108:0)
[23:06:59.263] Timer tick, asking Buildbucket for the build status
[23:06:59.378] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:59.378] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:109:0) after 1m0s
[23:07:59.507] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:109:0)
[23:07:59.507] Timer tick, asking Buildbucket for the build status
[23:07:59.609] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:110:0) after 1m0s
[23:08:59.634] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:110:0)
[23:08:59.634] Timer tick, asking Buildbucket for the build status
[23:08:59.798] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:59.798] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:111:0) after 1m0s
[23:09:59.823] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:111:0)
[23:09:59.823] Timer tick, asking Buildbucket for the build status
[23:09:59.885] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:59.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:112:0) after 1m0s
[23:10:59.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:112:0)
[23:10:59.915] Timer tick, asking Buildbucket for the build status
[23:11:00.198] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:00.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:113:0) after 1m0s
[23:12:00.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:113:0)
[23:12:00.233] Timer tick, asking Buildbucket for the build status
[23:12:00.433] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:00.434] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:114:0) after 1m0s
[23:13:00.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:114:0)
[23:13:00.455] Timer tick, asking Buildbucket for the build status
[23:13:00.614] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:00.614] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:115:0) after 1m0s
[23:14:00.635] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:115:0)
[23:14:00.635] Timer tick, asking Buildbucket for the build status
[23:14:00.710] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:00.710] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:116:0) after 1m0s
[23:15:02.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:116:0)
[23:15:02.644] Timer tick, asking Buildbucket for the build status
[23:15:03.233] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:03.233] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:117:0) after 1m0s
[23:16:03.331] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:117:0)
[23:16:03.331] Timer tick, asking Buildbucket for the build status
[23:16:03.446] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:03.446] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:118:0) after 1m0s
[23:17:03.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:118:0)
[23:17:03.479] Timer tick, asking Buildbucket for the build status
[23:17:03.543] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:03.543] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:119:0) after 1m0s
[23:18:03.666] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:119:0)
[23:18:03.666] Timer tick, asking Buildbucket for the build status
[23:18:03.730] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:03.730] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:120:0) after 1m0s
[23:19:03.753] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:120:0)
[23:19:03.753] Timer tick, asking Buildbucket for the build status
[23:19:03.828] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:03.828] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:121:0) after 1m0s
[23:20:03.853] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:121:0)
[23:20:03.853] Timer tick, asking Buildbucket for the build status
[23:20:03.951] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:03.951] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:122:0) after 1m0s
[23:21:03.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:122:0)
[23:21:03.978] Timer tick, asking Buildbucket for the build status
[23:21:04.109] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:123:0) after 1m0s
[23:22:04.134] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:123:0)
[23:22:04.134] Timer tick, asking Buildbucket for the build status
[23:22:04.384] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:04.384] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:124:0) after 1m0s
[23:23:04.395] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:124:0)
[23:23:04.395] Timer tick, asking Buildbucket for the build status
[23:23:04.539] Build 8868604354619966016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:04.539] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034034416753918000:125:0) after 1m0s
[23:23:16.214] Received PubSub notification, asking Buildbucket for the build status
[23:23:16.484] Build 8868604354619966016: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:23:16.484] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600644195582059",
"created_by": "project:chromium",
"created_ts": "1600636826838780",
"failure_reason": "BUILD_FAILURE",
"id": "8868604354619966016",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"355d28745436a638c0525cb92f55684375d5edb2\"}}",
"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.ntp.cards.promo.HomepagePromoTest#testDismiss_SwipeToDismiss\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"355d28745436a638c0525cb92f55684375d5edb2\", \"project\": \"chromium/src\"}], \"$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-69-wd5d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5134, \"got_angle_revision\": \"de335c16855f11d1f0a6f0b37bee30c8a09a6c1d\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"355d28745436a638c0525cb92f55684375d5edb2\", \"got_revision_cp\": \"refs/heads/master@{#808754}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"80dc1e8055bca07e8da6162bace5ee87f23faccf\", \"got_v8_revision_cp\": \"refs/heads/8.7.142@{#1}\", \"got_webrtc_revision\": \"14dbf82961c32ae9133bb672c88e146942062ada\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32143}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"355d28745436a638c0525cb92f55684375d5edb2\", \"swarm_hashes\": {\"android_browsertests\": \"0d21d71bb9d91ec2c781cb05c92e8a8cf1ff33c2\", \"chrome_public_test_apk\": \"5b336f3b686c871a94c8b9760f9e064a4778e96b\", \"content_browsertests\": \"9a0a90605ffb5aa5ac0e2dcff4a22e32b6a95b7c\", \"content_shell_test_apk\": \"a841f412b4fcbdfba45f0399d536b6d8c41e775b\", \"content_unittests\": \"3f6b37fa07fa28e03b0a8ba111722d68f0019e48\", \"unit_tests\": \"1e51f25106203da986d41de21e3b2090f3844223\"}, \"swarm_hashes_refs/heads/master(at){#808754}_without_patch\": {\"android_browsertests\": \"0d21d71bb9d91ec2c781cb05c92e8a8cf1ff33c2\", \"chrome_public_test_apk\": \"5b336f3b686c871a94c8b9760f9e064a4778e96b\", \"content_browsertests\": \"9a0a90605ffb5aa5ac0e2dcff4a22e32b6a95b7c\", \"content_shell_test_apk\": \"a841f412b4fcbdfba45f0399d536b6d8c41e775b\", \"content_unittests\": \"3f6b37fa07fa28e03b0a8ba111722d68f0019e48\", \"unit_tests\": \"1e51f25106203da986d41de21e3b2090f3844223\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_51fbd57b92446336655ba5d71606939b5bf22f086edf809f01e3703848d966de_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_8f1f1a811acb8ac7cee91f001f34cb98db55e889611ed2a31914ce7c854c1541_v2\", \"builder_953e900270162ec95f568ea264af47d43b9a29a15e01ede040ffa4d15b188919_v2\", \"builder_c2cb72df9b5576800da27660aa92911a0b5cae945b8f5ba1b2e88b64dd1a5b42_v2\", \"builder_df0918bfe72974da8c562c0af785205b640d6f631f017829e032ab97ab701012_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-69-wd5d\"], \"image\": [\"chrome-xenial-20091300-9b3346fad90\"], \"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\": [\"5531-8ab19d9\"], \"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.ntp.cards.promo.HomepagePromoTest#testDismiss_SwipeToDismiss\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600638019287507",
"status": "COMPLETED",
"status_changed_ts": "1600638024011389",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5134",
"builder:android-bfcache-rel",
"buildset:commit/git/355d28745436a638c0525cb92f55684375d5edb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/355d28745436a638c0525cb92f55684375d5edb2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034034416753918000",
"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/8868604354619966016/+/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:4ec635402e27c610",
"user_agent:luci-scheduler"
],
"updated_ts": "1600644195694422",
"url": "https://ci.chromium.org/b/8868604354619966016",
"utcnow_ts": "1600644196418960"
}
}
[23:23:16.484] Invocation finished in 2h2m51.064733729s with status FAILED