[22:41:28.295] New invocation is queued and will start shortly
[22:41:29.388] Starting the invocation (attempt 1)
[22:41:29.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:29.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:29.428] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020802160579897840",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"39ace65da97766d092322913b8f4c051d0723026\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9020802160579897840",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/39ace65da97766d092322913b8f4c051d0723026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39ace65da97766d092322913b8f4c051d0723026",
"gitiles_ref:refs/heads/master"
]
}
[22:41:30.163] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1613256089524403",
"id": "8855372098629088144",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39ace65da97766d092322913b8f4c051d0723026\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613256089970432",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7797",
"builder:android-bfcache-rel",
"buildset:commit/git/39ace65da97766d092322913b8f4c051d0723026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39ace65da97766d092322913b8f4c051d0723026",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020802160579897840",
"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/8855372098629088144/+/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": "1613256089970540",
"url": "https://ci.chromium.org/b/8855372098629088144",
"utcnow_ts": "1613256090154705"
}
}
[22:41:30.163] Task URL: https://ci.chromium.org/b/8855372098629088144
[22:41:30.163] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:2:0) after 1m0s
[22:42:30.212] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:2:0)
[22:42:30.212] Timer tick, asking Buildbucket for the build status
[22:42:30.288] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:30.288] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:3:0) after 1m0s
[22:43:30.348] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:3:0)
[22:43:30.348] Timer tick, asking Buildbucket for the build status
[22:43:30.512] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:30.512] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:4:0) after 1m0s
[22:44:30.589] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:4:0)
[22:44:30.590] Timer tick, asking Buildbucket for the build status
[22:44:30.663] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:30.663] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:5:0) after 1m0s
[22:45:30.721] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:5:0)
[22:45:30.721] Timer tick, asking Buildbucket for the build status
[22:45:30.789] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:30.789] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:6:0) after 1m0s
[22:46:30.819] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:6:0)
[22:46:30.819] Timer tick, asking Buildbucket for the build status
[22:46:31.023] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:31.023] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:7:0) after 1m0s
[22:47:31.063] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:7:0)
[22:47:31.063] Timer tick, asking Buildbucket for the build status
[22:47:31.152] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:31.152] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:8:0) after 1m0s
[22:48:31.246] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:8:0)
[22:48:31.246] Timer tick, asking Buildbucket for the build status
[22:48:31.311] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:31.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:9:0) after 1m0s
[22:49:31.323] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:9:0)
[22:49:31.323] Timer tick, asking Buildbucket for the build status
[22:49:31.412] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:31.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:10:0) after 1m0s
[22:50:31.428] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:10:0)
[22:50:31.428] Timer tick, asking Buildbucket for the build status
[22:50:31.486] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:31.486] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:11:0) after 1m0s
[22:51:31.539] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:11:0)
[22:51:31.539] Timer tick, asking Buildbucket for the build status
[22:51:31.603] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:31.603] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:12:0) after 1m0s
[22:52:31.599] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:12:0)
[22:52:31.599] Timer tick, asking Buildbucket for the build status
[22:52:31.779] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:31.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:13:0) after 1m0s
[22:53:31.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:13:0)
[22:53:31.800] Timer tick, asking Buildbucket for the build status
[22:53:31.872] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:31.872] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:14:0) after 1m0s
[22:54:31.922] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:14:0)
[22:54:31.922] Timer tick, asking Buildbucket for the build status
[22:54:32.002] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:32.002] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:15:0) after 1m0s
[22:55:32.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:15:0)
[22:55:32.061] Timer tick, asking Buildbucket for the build status
[22:55:32.121] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:32.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:16:0) after 1m0s
[22:56:32.105] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:16:0)
[22:56:32.105] Timer tick, asking Buildbucket for the build status
[22:56:32.201] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:32.201] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:17:0) after 1m0s
[22:57:32.232] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:17:0)
[22:57:32.232] Timer tick, asking Buildbucket for the build status
[22:57:32.292] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:32.292] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:18:0) after 1m0s
[22:58:32.313] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:18:0)
[22:58:32.313] Timer tick, asking Buildbucket for the build status
[22:58:32.376] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:32.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:19:0) after 1m0s
[22:59:32.408] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:19:0)
[22:59:32.408] Timer tick, asking Buildbucket for the build status
[22:59:32.471] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:32.471] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:20:0) after 1m0s
[23:00:32.514] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:20:0)
[23:00:32.514] Timer tick, asking Buildbucket for the build status
[23:00:32.588] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:32.589] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:21:0) after 1m0s
[23:01:32.626] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:21:0)
[23:01:32.626] Timer tick, asking Buildbucket for the build status
[23:01:32.709] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:32.709] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:22:0) after 1m0s
[23:02:32.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:22:0)
[23:02:32.706] Timer tick, asking Buildbucket for the build status
[23:02:32.787] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:32.787] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:23:0) after 1m0s
[23:03:32.806] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:23:0)
[23:03:32.806] Timer tick, asking Buildbucket for the build status
[23:03:33.148] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:33.148] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:24:0) after 1m0s
[23:04:33.199] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:24:0)
[23:04:33.199] Timer tick, asking Buildbucket for the build status
[23:04:33.275] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:33.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:25:0) after 1m0s
[23:05:33.415] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:25:0)
[23:05:33.415] Timer tick, asking Buildbucket for the build status
[23:05:33.489] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:33.489] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:26:0) after 1m0s
[23:06:33.558] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:26:0)
[23:06:33.558] Timer tick, asking Buildbucket for the build status
[23:06:33.699] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:33.699] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:27:0) after 1m0s
[23:07:33.771] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:27:0)
[23:07:33.771] Timer tick, asking Buildbucket for the build status
[23:07:33.931] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:33.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:28:0) after 1m0s
[23:08:34.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:28:0)
[23:08:34.043] Timer tick, asking Buildbucket for the build status
[23:08:34.102] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:34.102] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:29:0) after 1m0s
[23:09:34.292] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:29:0)
[23:09:34.292] Timer tick, asking Buildbucket for the build status
[23:09:34.386] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:34.386] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:30:0) after 1m0s
[23:10:34.508] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:30:0)
[23:10:34.509] Timer tick, asking Buildbucket for the build status
[23:10:34.588] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:34.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:31:0) after 1m0s
[23:11:34.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:31:0)
[23:11:34.609] Timer tick, asking Buildbucket for the build status
[23:11:34.680] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:34.680] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:32:0) after 1m0s
[23:12:34.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:32:0)
[23:12:34.750] Timer tick, asking Buildbucket for the build status
[23:12:34.824] Build 8855372098629088144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:34.824] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:33:0) after 1m0s
[23:13:08.180] Received PubSub notification, asking Buildbucket for the build status
[23:13:08.329] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:34.816] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:33:0)
[23:13:34.816] Timer tick, asking Buildbucket for the build status
[23:13:35.120] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:35:0) after 1m0s
[23:14:36.041] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:35:0)
[23:14:36.041] Timer tick, asking Buildbucket for the build status
[23:14:36.266] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:36.266] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:36:0) after 1m0s
[23:15:36.284] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:36:0)
[23:15:36.284] Timer tick, asking Buildbucket for the build status
[23:15:36.363] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:36.363] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:37:0) after 1m0s
[23:16:36.386] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:37:0)
[23:16:36.386] Timer tick, asking Buildbucket for the build status
[23:16:36.477] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.477] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:38:0) after 1m0s
[23:17:36.497] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:38:0)
[23:17:36.497] Timer tick, asking Buildbucket for the build status
[23:17:36.932] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.932] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:39:0) after 1m0s
[23:18:37.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:39:0)
[23:18:37.017] Timer tick, asking Buildbucket for the build status
[23:18:37.097] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:37.097] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:40:0) after 1m0s
[23:19:37.073] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:40:0)
[23:19:37.073] Timer tick, asking Buildbucket for the build status
[23:19:37.141] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:41:0) after 1m0s
[23:20:37.264] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:41:0)
[23:20:37.264] Timer tick, asking Buildbucket for the build status
[23:20:37.344] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:37.344] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:42:0) after 1m0s
[23:21:37.560] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:42:0)
[23:21:37.560] Timer tick, asking Buildbucket for the build status
[23:21:37.816] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.816] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:43:0) after 1m0s
[23:22:37.868] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:43:0)
[23:22:37.868] Timer tick, asking Buildbucket for the build status
[23:22:38.083] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:38.083] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:44:0) after 1m0s
[23:23:38.078] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:44:0)
[23:23:38.078] Timer tick, asking Buildbucket for the build status
[23:23:38.159] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:38.159] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:45:0) after 1m0s
[23:24:38.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:45:0)
[23:24:38.206] Timer tick, asking Buildbucket for the build status
[23:24:38.289] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:38.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:46:0) after 1m0s
[23:25:38.313] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:46:0)
[23:25:38.313] Timer tick, asking Buildbucket for the build status
[23:25:38.544] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:38.544] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:47:0) after 1m0s
[23:26:38.578] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:47:0)
[23:26:38.578] Timer tick, asking Buildbucket for the build status
[23:26:38.742] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:38.742] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:48:0) after 1m0s
[23:27:38.809] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:48:0)
[23:27:38.809] Timer tick, asking Buildbucket for the build status
[23:27:38.932] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:38.932] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:49:0) after 1m0s
[23:28:39.018] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:49:0)
[23:28:39.018] Timer tick, asking Buildbucket for the build status
[23:28:39.103] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:39.103] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:50:0) after 1m0s
[23:29:39.316] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:50:0)
[23:29:39.316] Timer tick, asking Buildbucket for the build status
[23:29:39.415] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:39.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:51:0) after 1m0s
[23:30:39.561] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:51:0)
[23:30:39.561] Timer tick, asking Buildbucket for the build status
[23:30:39.641] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:39.641] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:52:0) after 1m0s
[23:31:39.661] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:52:0)
[23:31:39.661] Timer tick, asking Buildbucket for the build status
[23:31:39.747] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:39.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:53:0) after 1m0s
[23:32:39.765] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:53:0)
[23:32:39.765] Timer tick, asking Buildbucket for the build status
[23:32:39.834] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:39.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:54:0) after 1m0s
[23:33:39.853] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:54:0)
[23:33:39.853] Timer tick, asking Buildbucket for the build status
[23:33:39.922] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:55:0) after 1m0s
[23:34:39.972] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:55:0)
[23:34:39.972] Timer tick, asking Buildbucket for the build status
[23:34:40.076] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:40.076] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:56:0) after 1m0s
[23:35:40.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:56:0)
[23:35:40.153] Timer tick, asking Buildbucket for the build status
[23:35:40.249] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:40.249] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:57:0) after 1m0s
[23:36:40.310] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:57:0)
[23:36:40.310] Timer tick, asking Buildbucket for the build status
[23:36:40.518] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:40.518] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:58:0) after 1m0s
[23:37:41.662] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:58:0)
[23:37:41.662] Timer tick, asking Buildbucket for the build status
[23:37:41.960] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:41.960] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:59:0) after 1m0s
[23:38:42.012] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:59:0)
[23:38:42.012] Timer tick, asking Buildbucket for the build status
[23:38:42.304] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:42.304] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:60:0) after 1m0s
[23:39:42.322] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:60:0)
[23:39:42.322] Timer tick, asking Buildbucket for the build status
[23:39:42.422] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:42.422] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:61:0) after 1m0s
[23:40:42.448] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:61:0)
[23:40:42.448] Timer tick, asking Buildbucket for the build status
[23:40:42.521] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:42.521] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:62:0) after 1m0s
[23:41:42.591] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:62:0)
[23:41:42.591] Timer tick, asking Buildbucket for the build status
[23:41:42.672] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:42.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:63:0) after 1m0s
[23:42:42.692] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:63:0)
[23:42:42.692] Timer tick, asking Buildbucket for the build status
[23:42:42.768] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:42.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:64:0) after 1m0s
[23:43:42.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:64:0)
[23:43:42.749] Timer tick, asking Buildbucket for the build status
[23:43:43.041] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:43.041] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:65:0) after 1m0s
[23:44:43.114] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:65:0)
[23:44:43.114] Timer tick, asking Buildbucket for the build status
[23:44:43.392] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:43.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:66:0) after 1m0s
[23:45:43.423] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:66:0)
[23:45:43.423] Timer tick, asking Buildbucket for the build status
[23:45:43.875] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:43.875] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:67:0) after 1m0s
[23:46:43.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:67:0)
[23:46:43.943] Timer tick, asking Buildbucket for the build status
[23:46:44.045] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:44.045] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:68:0) after 1m0s
[23:47:44.165] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:68:0)
[23:47:44.165] Timer tick, asking Buildbucket for the build status
[23:47:44.253] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:44.253] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:69:0) after 1m0s
[23:48:44.289] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:69:0)
[23:48:44.289] Timer tick, asking Buildbucket for the build status
[23:48:44.377] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:44.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:70:0) after 1m0s
[23:49:44.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:70:0)
[23:49:44.437] Timer tick, asking Buildbucket for the build status
[23:49:44.513] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:44.513] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:71:0) after 1m0s
[23:50:44.564] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:71:0)
[23:50:44.564] Timer tick, asking Buildbucket for the build status
[23:50:44.704] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:44.704] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:72:0) after 1m0s
[23:51:44.729] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:72:0)
[23:51:44.729] Timer tick, asking Buildbucket for the build status
[23:51:44.928] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:44.928] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:73:0) after 1m0s
[23:52:44.929] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:73:0)
[23:52:44.929] Timer tick, asking Buildbucket for the build status
[23:52:45.005] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:45.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:74:0) after 1m0s
[23:53:45.112] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:74:0)
[23:53:45.112] Timer tick, asking Buildbucket for the build status
[23:53:45.198] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:45.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:75:0) after 1m0s
[23:54:45.257] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:75:0)
[23:54:45.257] Timer tick, asking Buildbucket for the build status
[23:54:45.327] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:76:0) after 1m0s
[23:55:45.304] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:76:0)
[23:55:45.304] Timer tick, asking Buildbucket for the build status
[23:55:45.377] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:45.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:77:0) after 1m0s
[23:56:45.453] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:77:0)
[23:56:45.453] Timer tick, asking Buildbucket for the build status
[23:56:45.546] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:45.546] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:78:0) after 1m0s
[23:57:45.591] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:78:0)
[23:57:45.591] Timer tick, asking Buildbucket for the build status
[23:57:45.677] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:45.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:79:0) after 1m0s
[23:58:45.767] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:79:0)
[23:58:45.767] Timer tick, asking Buildbucket for the build status
[23:58:45.867] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:45.867] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:80:0) after 1m0s
[23:59:46.066] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:80:0)
[23:59:46.066] Timer tick, asking Buildbucket for the build status
[23:59:46.179] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:46.179] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:81:0) after 1m0s
[00:00:46.370] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:81:0)
[00:00:46.370] Timer tick, asking Buildbucket for the build status
[00:00:46.457] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:46.457] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:82:0) after 1m0s
[00:01:46.554] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:82:0)
[00:01:46.554] Timer tick, asking Buildbucket for the build status
[00:01:46.743] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:46.743] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:83:0) after 1m0s
[00:02:46.782] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:83:0)
[00:02:46.782] Timer tick, asking Buildbucket for the build status
[00:02:47.193] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:84:0) after 1m0s
[00:03:47.484] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:84:0)
[00:03:47.484] Timer tick, asking Buildbucket for the build status
[00:03:47.738] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:47.738] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:85:0) after 1m0s
[00:04:47.862] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:85:0)
[00:04:47.862] Timer tick, asking Buildbucket for the build status
[00:04:48.138] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:48.139] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:86:0) after 1m0s
[00:05:48.143] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:86:0)
[00:05:48.143] Timer tick, asking Buildbucket for the build status
[00:05:48.571] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:87:0) after 1m0s
[00:06:48.736] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:87:0)
[00:06:48.736] Timer tick, asking Buildbucket for the build status
[00:06:48.830] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:48.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:88:0) after 1m0s
[00:07:48.852] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:88:0)
[00:07:48.852] Timer tick, asking Buildbucket for the build status
[00:07:48.919] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:48.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:89:0) after 1m0s
[00:08:49.058] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:89:0)
[00:08:49.058] Timer tick, asking Buildbucket for the build status
[00:08:49.276] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:49.277] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:90:0) after 1m0s
[00:09:49.490] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:90:0)
[00:09:49.490] Timer tick, asking Buildbucket for the build status
[00:09:49.655] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:49.655] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:91:0) after 1m0s
[00:10:49.752] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:91:0)
[00:10:49.752] Timer tick, asking Buildbucket for the build status
[00:10:49.836] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:49.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:92:0) after 1m0s
[00:11:49.886] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:92:0)
[00:11:49.886] Timer tick, asking Buildbucket for the build status
[00:11:50.106] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:50.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:93:0) after 1m0s
[00:12:50.171] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:93:0)
[00:12:50.171] Timer tick, asking Buildbucket for the build status
[00:12:50.250] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:50.250] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:94:0) after 1m0s
[00:13:50.295] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:94:0)
[00:13:50.295] Timer tick, asking Buildbucket for the build status
[00:13:50.392] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:50.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:95:0) after 1m0s
[00:14:50.595] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:95:0)
[00:14:50.595] Timer tick, asking Buildbucket for the build status
[00:14:51.101] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:51.101] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:96:0) after 1m0s
[00:15:51.327] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:96:0)
[00:15:51.327] Timer tick, asking Buildbucket for the build status
[00:15:51.421] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:51.421] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:97:0) after 1m0s
[00:16:51.563] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:97:0)
[00:16:51.563] Timer tick, asking Buildbucket for the build status
[00:16:51.772] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:51.772] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:98:0) after 1m0s
[00:17:53.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:98:0)
[00:17:53.749] Timer tick, asking Buildbucket for the build status
[00:17:53.832] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:99:0) after 1m0s
[00:18:54.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:99:0)
[00:18:54.059] Timer tick, asking Buildbucket for the build status
[00:18:54.133] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:54.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:100:0) after 1m0s
[00:19:54.444] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:100:0)
[00:19:54.444] Timer tick, asking Buildbucket for the build status
[00:19:54.618] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:54.618] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:101:0) after 1m0s
[00:20:54.739] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:101:0)
[00:20:54.739] Timer tick, asking Buildbucket for the build status
[00:20:54.888] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:54.889] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:102:0) after 1m0s
[00:21:55.167] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:102:0)
[00:21:55.167] Timer tick, asking Buildbucket for the build status
[00:21:55.317] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:55.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:103:0) after 1m0s
[00:22:55.436] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:103:0)
[00:22:55.436] Timer tick, asking Buildbucket for the build status
[00:22:55.528] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:55.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:104:0) after 1m0s
[00:23:55.733] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:104:0)
[00:23:55.733] Timer tick, asking Buildbucket for the build status
[00:23:55.830] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:55.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:105:0) after 1m0s
[00:24:55.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:105:0)
[00:24:55.946] Timer tick, asking Buildbucket for the build status
[00:24:56.026] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:56.026] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:106:0) after 1m0s
[00:25:56.266] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:106:0)
[00:25:56.266] Timer tick, asking Buildbucket for the build status
[00:25:56.358] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:56.358] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:107:0) after 1m0s
[00:26:56.503] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:107:0)
[00:26:56.503] Timer tick, asking Buildbucket for the build status
[00:26:57.356] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:57.356] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:108:0) after 1m0s
[00:27:57.439] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:108:0)
[00:27:57.439] Timer tick, asking Buildbucket for the build status
[00:27:57.800] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:57.800] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:109:0) after 1m0s
[00:28:57.821] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:109:0)
[00:28:57.821] Timer tick, asking Buildbucket for the build status
[00:28:57.904] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:57.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:110:0) after 1m0s
[00:29:57.929] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:110:0)
[00:29:57.929] Timer tick, asking Buildbucket for the build status
[00:29:58.006] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:58.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:111:0) after 1m0s
[00:30:58.054] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:111:0)
[00:30:58.054] Timer tick, asking Buildbucket for the build status
[00:30:58.205] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:58.205] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:112:0) after 1m0s
[00:31:58.248] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:112:0)
[00:31:58.248] Timer tick, asking Buildbucket for the build status
[00:31:58.376] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:58.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:113:0) after 1m0s
[00:32:58.494] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:113:0)
[00:32:58.494] Timer tick, asking Buildbucket for the build status
[00:32:58.587] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:58.587] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:114:0) after 1m0s
[00:33:58.592] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:114:0)
[00:33:58.592] Timer tick, asking Buildbucket for the build status
[00:33:58.664] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:58.664] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:115:0) after 1m0s
[00:34:58.704] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:115:0)
[00:34:58.704] Timer tick, asking Buildbucket for the build status
[00:34:58.897] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:58.897] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:116:0) after 1m0s
[00:35:58.939] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:116:0)
[00:35:58.939] Timer tick, asking Buildbucket for the build status
[00:35:59.283] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:59.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:117:0) after 1m0s
[00:36:59.396] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:117:0)
[00:36:59.396] Timer tick, asking Buildbucket for the build status
[00:36:59.774] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:59.774] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:118:0) after 1m0s
[00:37:59.832] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:118:0)
[00:37:59.832] Timer tick, asking Buildbucket for the build status
[00:37:59.949] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:59.949] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:119:0) after 1m0s
[00:38:59.992] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:119:0)
[00:38:59.992] Timer tick, asking Buildbucket for the build status
[00:39:00.281] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:00.281] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:120:0) after 1m0s
[00:40:00.339] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:120:0)
[00:40:00.339] Timer tick, asking Buildbucket for the build status
[00:40:00.551] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:00.551] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:121:0) after 1m0s
[00:41:00.569] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:121:0)
[00:41:00.569] Timer tick, asking Buildbucket for the build status
[00:41:00.720] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:00.720] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:122:0) after 1m0s
[00:42:00.709] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:122:0)
[00:42:00.709] Timer tick, asking Buildbucket for the build status
[00:42:00.865] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:00.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:123:0) after 1m0s
[00:43:00.933] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:123:0)
[00:43:00.933] Timer tick, asking Buildbucket for the build status
[00:43:01.023] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:01.023] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:124:0) after 1m0s
[00:44:01.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:124:0)
[00:44:01.306] Timer tick, asking Buildbucket for the build status
[00:44:01.408] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:01.408] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:125:0) after 1m0s
[00:45:01.433] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:125:0)
[00:45:01.433] Timer tick, asking Buildbucket for the build status
[00:45:01.541] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:01.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:126:0) after 1m0s
[00:46:01.602] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:126:0)
[00:46:01.602] Timer tick, asking Buildbucket for the build status
[00:46:01.723] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:01.724] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:127:0) after 1m0s
[00:47:01.731] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:127:0)
[00:47:01.731] Timer tick, asking Buildbucket for the build status
[00:47:01.911] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:01.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:128:0) after 1m0s
[00:48:02.223] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:128:0)
[00:48:02.223] Timer tick, asking Buildbucket for the build status
[00:48:03.327] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:03.327] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:129:0) after 1m0s
[00:49:03.486] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:129:0)
[00:49:03.486] Timer tick, asking Buildbucket for the build status
[00:49:03.608] Build 8855372098629088144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:03.608] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020802160579897840:130:0) after 1m0s
[00:49:25.132] Received PubSub notification, asking Buildbucket for the build status
[00:49:25.392] Build 8855372098629088144: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:49:25.392] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613263762957120",
"created_by": "project:chromium",
"created_ts": "1613256089524403",
"failure_reason": "BUILD_FAILURE",
"id": "8855372098629088144",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39ace65da97766d092322913b8f4c051d0723026\"}}",
"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.metrics.StartupLoadingMetricsTest#testBackgroundedPageNotRecorded\"}, \"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\": \"39ace65da97766d092322913b8f4c051d0723026\", \"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-central1-b-8-84-2ilu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7797, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"39ace65da97766d092322913b8f4c051d0723026\", \"got_revision_cp\": \"refs/heads/master@{#853867}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"554b7ee535b3eca3f041dc45d00de5cbd490729c\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39ace65da97766d092322913b8f4c051d0723026\", \"swarm_hashes_refs/heads/master(at){#853867}_without_patch\": {\"android_browsertests\": \"cafbcb7561b844c4624457cf419d8575f73b26d4\", \"chrome_public_test_apk\": \"b509ba3ad7929748b1b8cce55fc8b94a008a623d\", \"content_browsertests\": \"94b387386960a1c2895bd0e28a77e0150470468b\", \"content_shell_test_apk\": \"163e13eadd01cab0f8ab2bc4ae3a8c0ca9b3f2a4\", \"content_unittests\": \"7775d7b6877ff458f4cb845f4b8ce41016fce3db\", \"unit_tests\": \"3f8f54cf66b76e7c0cc764b7d78a9bb26de88f68\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_53a369b6e7b3f2409540d71b27a68c8bcf1cb73633e70dba0b4b8b173a0aec5e_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_a2762fc34916ccddaf93df3ba974050968da64cd82521aaf473d97885fea8846_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_ce300ca2149b7ede2a11dc5f29b170d3e5c2bdd2b201177604ba7c6bb221adeb_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-84-2ilu\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"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.metrics.StartupLoadingMetricsTest#testBackgroundedPageNotRecorded\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613257980508262",
"status": "COMPLETED",
"status_changed_ts": "1613257987880698",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7797",
"builder:android-bfcache-rel",
"buildset:commit/git/39ace65da97766d092322913b8f4c051d0723026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39ace65da97766d092322913b8f4c051d0723026",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020802160579897840",
"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/8855372098629088144/+/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:51b65feae192df10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613263763038877",
"url": "https://ci.chromium.org/b/8855372098629088144",
"utcnow_ts": "1613263765380632"
}
}
[00:49:25.392] Invocation finished in 2h7m57.112094837s with status FAILED