[07:02:02.179] New invocation is queued and will start shortly
[07:02:03.666] Starting the invocation (attempt 1)
[07:02:03.734] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:02:03.734] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:02:03.734] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9042332745776636048",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"388818e962ccee2a23783866f8e5eb93ac54ed79\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9042332745776636048",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/388818e962ccee2a23783866f8e5eb93ac54ed79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/388818e962ccee2a23783866f8e5eb93ac54ed79",
"gitiles_ref:refs/heads/master"
]
}
[07:02:05.367] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1592722923955484",
"id": "8876902683248704624",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"388818e962ccee2a23783866f8e5eb93ac54ed79\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1592722925134136",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3469",
"builder:android-bfcache-rel",
"buildset:commit/git/388818e962ccee2a23783866f8e5eb93ac54ed79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/388818e962ccee2a23783866f8e5eb93ac54ed79",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042332745776636048",
"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/8876902683248704624/+/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": "1592722925134300",
"url": "https://ci.chromium.org/b/8876902683248704624",
"utcnow_ts": "1592722925357713"
}
}
[07:02:05.367] Task URL: https://ci.chromium.org/b/8876902683248704624
[07:02:05.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:2:0) after 1m0s
[07:03:05.373] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:2:0)
[07:03:05.373] Timer tick, asking Buildbucket for the build status
[07:03:05.503] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:05.503] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:3:0) after 1m0s
[07:04:05.543] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:3:0)
[07:04:05.543] Timer tick, asking Buildbucket for the build status
[07:04:05.994] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:05.994] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:4:0) after 1m0s
[07:05:06.319] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:4:0)
[07:05:06.319] Timer tick, asking Buildbucket for the build status
[07:05:06.509] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:5:0) after 1m0s
[07:06:06.680] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:5:0)
[07:06:06.680] Timer tick, asking Buildbucket for the build status
[07:06:07.024] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:07.024] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:6:0) after 1m0s
[07:07:07.690] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:6:0)
[07:07:07.690] Timer tick, asking Buildbucket for the build status
[07:07:08.171] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:08.171] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:7:0) after 1m0s
[07:08:08.491] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:7:0)
[07:08:08.491] Timer tick, asking Buildbucket for the build status
[07:08:09.404] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:09.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:8:0) after 1m0s
[07:09:09.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:8:0)
[07:09:09.527] Timer tick, asking Buildbucket for the build status
[07:09:09.690] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:09.690] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:9:0) after 1m0s
[07:10:09.717] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:9:0)
[07:10:09.717] Timer tick, asking Buildbucket for the build status
[07:10:10.533] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:10.533] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:10:0) after 1m0s
[07:11:10.674] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:10:0)
[07:11:10.674] Timer tick, asking Buildbucket for the build status
[07:11:11.336] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:11.336] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:11:0) after 1m0s
[07:12:11.390] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:11:0)
[07:12:11.390] Timer tick, asking Buildbucket for the build status
[07:12:11.674] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:11.674] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:12:0) after 1m0s
[07:13:11.694] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:12:0)
[07:13:11.694] Timer tick, asking Buildbucket for the build status
[07:13:11.801] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:11.801] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:13:0) after 1m0s
[07:14:12.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:13:0)
[07:14:12.021] Timer tick, asking Buildbucket for the build status
[07:14:12.449] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:14:0) after 1m0s
[07:15:12.684] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:14:0)
[07:15:12.684] Timer tick, asking Buildbucket for the build status
[07:15:12.852] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:12.852] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:15:0) after 1m0s
[07:16:13.297] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:15:0)
[07:16:13.297] Timer tick, asking Buildbucket for the build status
[07:16:13.903] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:13.903] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:16:0) after 1m0s
[07:17:13.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:16:0)
[07:17:13.954] Timer tick, asking Buildbucket for the build status
[07:17:14.188] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:14.188] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:17:0) after 1m0s
[07:18:14.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:17:0)
[07:18:14.385] Timer tick, asking Buildbucket for the build status
[07:18:14.678] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:14.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:18:0) after 1m0s
[07:19:14.944] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:18:0)
[07:19:14.944] Timer tick, asking Buildbucket for the build status
[07:19:15.265] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:15.265] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:19:0) after 1m0s
[07:20:15.410] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:19:0)
[07:20:15.410] Timer tick, asking Buildbucket for the build status
[07:20:15.542] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:15.542] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:20:0) after 1m0s
[07:21:15.836] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:20:0)
[07:21:15.836] Timer tick, asking Buildbucket for the build status
[07:21:16.333] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:16.333] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:21:0) after 1m0s
[07:22:16.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:21:0)
[07:22:16.375] Timer tick, asking Buildbucket for the build status
[07:22:16.749] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:16.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:22:0) after 1m0s
[07:23:16.808] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:22:0)
[07:23:16.808] Timer tick, asking Buildbucket for the build status
[07:23:17.043] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:17.043] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:23:0) after 1m0s
[07:24:17.139] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:23:0)
[07:24:17.139] Timer tick, asking Buildbucket for the build status
[07:24:17.396] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:17.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:24:0) after 1m0s
[07:25:18.198] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:24:0)
[07:25:18.198] Timer tick, asking Buildbucket for the build status
[07:25:18.576] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:18.576] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:25:0) after 1m0s
[07:26:18.658] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:25:0)
[07:26:18.658] Timer tick, asking Buildbucket for the build status
[07:26:18.796] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:18.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:26:0) after 1m0s
[07:27:18.768] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:26:0)
[07:27:18.768] Timer tick, asking Buildbucket for the build status
[07:27:18.867] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:18.867] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:27:0) after 1m0s
[07:28:18.895] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:27:0)
[07:28:18.895] Timer tick, asking Buildbucket for the build status
[07:28:19.082] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:19.082] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:28:0) after 1m0s
[07:29:19.145] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:28:0)
[07:29:19.145] Timer tick, asking Buildbucket for the build status
[07:29:19.334] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:19.334] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:29:0) after 1m0s
[07:30:19.379] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:29:0)
[07:30:19.379] Timer tick, asking Buildbucket for the build status
[07:30:19.527] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:19.527] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:30:0) after 1m0s
[07:31:19.840] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:30:0)
[07:31:19.840] Timer tick, asking Buildbucket for the build status
[07:31:20.042] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:20.042] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:31:0) after 1m0s
[07:32:20.292] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:31:0)
[07:32:20.292] Timer tick, asking Buildbucket for the build status
[07:32:20.708] Build 8876902683248704624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:20.708] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:32:0) after 1m0s
[07:32:33.249] Received PubSub notification, asking Buildbucket for the build status
[07:32:33.345] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:20.971] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:32:0)
[07:33:20.971] Timer tick, asking Buildbucket for the build status
[07:33:21.324] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:21.324] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:34:0) after 1m0s
[07:34:21.457] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:34:0)
[07:34:21.457] Timer tick, asking Buildbucket for the build status
[07:34:21.757] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:21.757] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:35:0) after 1m0s
[07:35:21.903] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:35:0)
[07:35:21.903] Timer tick, asking Buildbucket for the build status
[07:35:22.146] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:22.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:36:0) after 1m0s
[07:36:22.224] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:36:0)
[07:36:22.224] Timer tick, asking Buildbucket for the build status
[07:36:22.612] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:22.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:37:0) after 1m0s
[07:37:22.786] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:37:0)
[07:37:22.786] Timer tick, asking Buildbucket for the build status
[07:37:23.962] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:23.962] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:38:0) after 1m0s
[07:38:24.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:38:0)
[07:38:24.061] Timer tick, asking Buildbucket for the build status
[07:38:24.835] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:24.835] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:39:0) after 1m0s
[07:39:24.895] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:39:0)
[07:39:24.895] Timer tick, asking Buildbucket for the build status
[07:39:25.314] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:25.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:40:0) after 1m0s
[07:40:25.355] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:40:0)
[07:40:25.355] Timer tick, asking Buildbucket for the build status
[07:40:25.537] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:25.537] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:41:0) after 1m0s
[07:41:25.589] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:41:0)
[07:41:25.589] Timer tick, asking Buildbucket for the build status
[07:41:26.961] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:26.961] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:42:0) after 1m0s
[07:42:27.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:42:0)
[07:42:27.043] Timer tick, asking Buildbucket for the build status
[07:42:27.199] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:27.199] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:43:0) after 1m0s
[07:43:27.264] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:43:0)
[07:43:27.264] Timer tick, asking Buildbucket for the build status
[07:43:27.494] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:27.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:44:0) after 1m0s
[07:44:27.542] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:44:0)
[07:44:27.542] Timer tick, asking Buildbucket for the build status
[07:44:27.749] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:27.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:45:0) after 1m0s
[07:45:27.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:45:0)
[07:45:27.770] Timer tick, asking Buildbucket for the build status
[07:45:27.927] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:27.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:46:0) after 1m0s
[07:46:27.960] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:46:0)
[07:46:27.960] Timer tick, asking Buildbucket for the build status
[07:46:28.347] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:47:0) after 1m0s
[07:47:28.374] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:47:0)
[07:47:28.374] Timer tick, asking Buildbucket for the build status
[07:47:28.543] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:28.543] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:48:0) after 1m0s
[07:48:28.591] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:48:0)
[07:48:28.591] Timer tick, asking Buildbucket for the build status
[07:48:28.876] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:28.876] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:49:0) after 1m0s
[07:49:28.919] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:49:0)
[07:49:28.919] Timer tick, asking Buildbucket for the build status
[07:49:29.156] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:29.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:50:0) after 1m0s
[07:50:29.287] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:50:0)
[07:50:29.287] Timer tick, asking Buildbucket for the build status
[07:50:29.508] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:29.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:51:0) after 1m0s
[07:51:29.510] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:51:0)
[07:51:29.510] Timer tick, asking Buildbucket for the build status
[07:51:29.628] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:29.628] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:52:0) after 1m0s
[07:52:29.789] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:52:0)
[07:52:29.789] Timer tick, asking Buildbucket for the build status
[07:52:30.217] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:30.217] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:53:0) after 1m0s
[07:53:30.282] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:53:0)
[07:53:30.282] Timer tick, asking Buildbucket for the build status
[07:53:32.462] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:32.462] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:54:0) after 1m0s
[07:54:32.485] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:54:0)
[07:54:32.485] Timer tick, asking Buildbucket for the build status
[07:54:32.990] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:32.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:55:0) after 1m0s
[07:55:33.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:55:0)
[07:55:33.017] Timer tick, asking Buildbucket for the build status
[07:55:33.569] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:33.569] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:56:0) after 1m0s
[07:56:33.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:56:0)
[07:56:33.597] Timer tick, asking Buildbucket for the build status
[07:56:33.719] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:33.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:57:0) after 1m0s
[07:57:33.715] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:57:0)
[07:57:33.715] Timer tick, asking Buildbucket for the build status
[07:57:33.905] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:33.905] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:58:0) after 1m0s
[07:58:33.909] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:58:0)
[07:58:33.909] Timer tick, asking Buildbucket for the build status
[07:58:35.034] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:35.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:59:0) after 1m0s
[07:59:35.053] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:59:0)
[07:59:35.053] Timer tick, asking Buildbucket for the build status
[07:59:35.206] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:35.206] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:60:0) after 1m0s
[08:00:35.263] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:60:0)
[08:00:35.263] Timer tick, asking Buildbucket for the build status
[08:00:35.399] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:35.399] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:61:0) after 1m0s
[08:01:35.431] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:61:0)
[08:01:35.431] Timer tick, asking Buildbucket for the build status
[08:01:35.842] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:35.842] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:62:0) after 1m0s
[08:02:35.846] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:62:0)
[08:02:35.846] Timer tick, asking Buildbucket for the build status
[08:02:36.101] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:36.101] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:63:0) after 1m0s
[08:03:36.222] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:63:0)
[08:03:36.222] Timer tick, asking Buildbucket for the build status
[08:03:36.650] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:36.650] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:64:0) after 1m0s
[08:04:36.714] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:64:0)
[08:04:36.714] Timer tick, asking Buildbucket for the build status
[08:04:36.830] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:36.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:65:0) after 1m0s
[08:05:36.937] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:65:0)
[08:05:36.937] Timer tick, asking Buildbucket for the build status
[08:05:37.195] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:37.195] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:66:0) after 1m0s
[08:06:37.291] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:66:0)
[08:06:37.291] Timer tick, asking Buildbucket for the build status
[08:06:37.568] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:37.568] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:67:0) after 1m0s
[08:07:37.579] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:67:0)
[08:07:37.579] Timer tick, asking Buildbucket for the build status
[08:07:37.717] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:37.717] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:68:0) after 1m0s
[08:08:37.742] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:68:0)
[08:08:37.742] Timer tick, asking Buildbucket for the build status
[08:08:38.118] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:69:0) after 1m0s
[08:09:38.141] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:69:0)
[08:09:38.141] Timer tick, asking Buildbucket for the build status
[08:09:38.774] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:38.774] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:70:0) after 1m0s
[08:10:38.844] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:70:0)
[08:10:38.844] Timer tick, asking Buildbucket for the build status
[08:10:39.396] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:39.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:71:0) after 1m0s
[08:11:39.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:71:0)
[08:11:39.465] Timer tick, asking Buildbucket for the build status
[08:11:39.605] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:39.605] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:72:0) after 1m0s
[08:12:39.723] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:72:0)
[08:12:39.723] Timer tick, asking Buildbucket for the build status
[08:12:39.987] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:39.987] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:73:0) after 1m0s
[08:13:40.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:73:0)
[08:13:40.129] Timer tick, asking Buildbucket for the build status
[08:13:42.348] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:42.348] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:74:0) after 1m0s
[08:14:42.371] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:74:0)
[08:14:42.371] Timer tick, asking Buildbucket for the build status
[08:14:42.668] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:42.668] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:75:0) after 1m0s
[08:15:42.703] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:75:0)
[08:15:42.703] Timer tick, asking Buildbucket for the build status
[08:15:43.072] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:43.072] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:76:0) after 1m0s
[08:16:43.107] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:76:0)
[08:16:43.107] Timer tick, asking Buildbucket for the build status
[08:16:43.441] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:43.441] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:77:0) after 1m0s
[08:17:43.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:77:0)
[08:17:43.479] Timer tick, asking Buildbucket for the build status
[08:17:43.730] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:43.730] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:78:0) after 1m0s
[08:18:43.753] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:78:0)
[08:18:43.753] Timer tick, asking Buildbucket for the build status
[08:18:45.627] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:45.627] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:79:0) after 1m0s
[08:19:45.710] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:79:0)
[08:19:45.710] Timer tick, asking Buildbucket for the build status
[08:19:45.865] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:45.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:80:0) after 1m0s
[08:20:45.890] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:80:0)
[08:20:45.891] Timer tick, asking Buildbucket for the build status
[08:20:46.058] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:46.058] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:81:0) after 1m0s
[08:21:46.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:81:0)
[08:21:46.043] Timer tick, asking Buildbucket for the build status
[08:21:46.403] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:46.403] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:82:0) after 1m0s
[08:22:46.531] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:82:0)
[08:22:46.531] Timer tick, asking Buildbucket for the build status
[08:22:46.996] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:46.996] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:83:0) after 1m0s
[08:23:47.042] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:83:0)
[08:23:47.042] Timer tick, asking Buildbucket for the build status
[08:23:47.210] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:47.210] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:84:0) after 1m0s
[08:24:47.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:84:0)
[08:24:47.239] Timer tick, asking Buildbucket for the build status
[08:24:47.339] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:47.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:85:0) after 1m0s
[08:25:47.413] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:85:0)
[08:25:47.413] Timer tick, asking Buildbucket for the build status
[08:25:48.198] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:48.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:86:0) after 1m0s
[08:26:48.293] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:86:0)
[08:26:48.293] Timer tick, asking Buildbucket for the build status
[08:26:48.448] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:48.448] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:87:0) after 1m0s
[08:27:48.518] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:87:0)
[08:27:48.518] Timer tick, asking Buildbucket for the build status
[08:27:48.750] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:48.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:88:0) after 1m0s
[08:28:48.800] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:88:0)
[08:28:48.800] Timer tick, asking Buildbucket for the build status
[08:28:49.689] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:49.689] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:89:0) after 1m0s
[08:29:49.709] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:89:0)
[08:29:49.709] Timer tick, asking Buildbucket for the build status
[08:29:49.880] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:49.880] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:90:0) after 1m0s
[08:30:49.906] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:90:0)
[08:30:49.906] Timer tick, asking Buildbucket for the build status
[08:30:51.796] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:51.796] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:91:0) after 1m0s
[08:31:51.912] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:91:0)
[08:31:51.912] Timer tick, asking Buildbucket for the build status
[08:31:52.391] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:52.391] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:92:0) after 1m0s
[08:32:52.439] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:92:0)
[08:32:52.439] Timer tick, asking Buildbucket for the build status
[08:32:52.691] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:52.691] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:93:0) after 1m0s
[08:33:52.818] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:93:0)
[08:33:52.818] Timer tick, asking Buildbucket for the build status
[08:33:52.986] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:52.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:94:0) after 1m0s
[08:34:53.015] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:94:0)
[08:34:53.015] Timer tick, asking Buildbucket for the build status
[08:34:53.477] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:53.477] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:95:0) after 1m0s
[08:35:53.604] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:95:0)
[08:35:53.604] Timer tick, asking Buildbucket for the build status
[08:35:53.784] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:53.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:96:0) after 1m0s
[08:36:53.913] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:96:0)
[08:36:53.913] Timer tick, asking Buildbucket for the build status
[08:36:54.402] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:54.402] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:97:0) after 1m0s
[08:37:54.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:97:0)
[08:37:54.479] Timer tick, asking Buildbucket for the build status
[08:37:54.768] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:54.769] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:98:0) after 1m0s
[08:38:54.884] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:98:0)
[08:38:54.884] Timer tick, asking Buildbucket for the build status
[08:38:55.041] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:55.041] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:99:0) after 1m0s
[08:39:55.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:99:0)
[08:39:55.208] Timer tick, asking Buildbucket for the build status
[08:39:55.469] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:55.469] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:100:0) after 1m0s
[08:40:55.500] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:100:0)
[08:40:55.500] Timer tick, asking Buildbucket for the build status
[08:40:55.712] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:55.712] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:101:0) after 1m0s
[08:41:55.719] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:101:0)
[08:41:55.719] Timer tick, asking Buildbucket for the build status
[08:41:57.524] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:57.524] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:102:0) after 1m0s
[08:42:57.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:102:0)
[08:42:57.565] Timer tick, asking Buildbucket for the build status
[08:42:57.741] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:57.741] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:103:0) after 1m0s
[08:43:57.766] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:103:0)
[08:43:57.766] Timer tick, asking Buildbucket for the build status
[08:43:57.948] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:58.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:104:0) after 1m0s
[08:44:58.067] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:104:0)
[08:44:58.067] Timer tick, asking Buildbucket for the build status
[08:44:58.381] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:58.381] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:105:0) after 1m0s
[08:45:58.404] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:105:0)
[08:45:58.404] Timer tick, asking Buildbucket for the build status
[08:45:58.601] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:58.601] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:106:0) after 1m0s
[08:46:58.628] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:106:0)
[08:46:58.628] Timer tick, asking Buildbucket for the build status
[08:46:58.757] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:58.757] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:107:0) after 1m0s
[08:47:58.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:107:0)
[08:47:58.791] Timer tick, asking Buildbucket for the build status
[08:47:59.095] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:59.095] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:108:0) after 1m0s
[08:48:59.126] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:108:0)
[08:48:59.126] Timer tick, asking Buildbucket for the build status
[08:48:59.264] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:59.264] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:109:0) after 1m0s
[08:49:59.370] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:109:0)
[08:49:59.370] Timer tick, asking Buildbucket for the build status
[08:49:59.732] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:59.732] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:110:0) after 1m0s
[08:50:59.754] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:110:0)
[08:50:59.754] Timer tick, asking Buildbucket for the build status
[08:50:59.905] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:59.905] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:111:0) after 1m0s
[08:51:59.979] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:111:0)
[08:51:59.979] Timer tick, asking Buildbucket for the build status
[08:52:00.179] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:00.179] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:112:0) after 1m0s
[08:53:00.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:112:0)
[08:53:00.206] Timer tick, asking Buildbucket for the build status
[08:53:00.424] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:00.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:113:0) after 1m0s
[08:54:00.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:113:0)
[08:54:00.639] Timer tick, asking Buildbucket for the build status
[08:54:00.906] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:00.906] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:114:0) after 1m0s
[08:55:02.240] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:114:0)
[08:55:02.240] Timer tick, asking Buildbucket for the build status
[08:55:02.382] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:02.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:115:0) after 1m0s
[08:56:02.423] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:115:0)
[08:56:02.423] Timer tick, asking Buildbucket for the build status
[08:56:02.633] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:02.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:116:0) after 1m0s
[08:57:02.673] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:116:0)
[08:57:02.673] Timer tick, asking Buildbucket for the build status
[08:57:03.118] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:03.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:117:0) after 1m0s
[08:58:03.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:117:0)
[08:58:03.206] Timer tick, asking Buildbucket for the build status
[08:58:03.476] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:03.476] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:118:0) after 1m0s
[08:59:03.538] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:118:0)
[08:59:03.538] Timer tick, asking Buildbucket for the build status
[08:59:03.877] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:03.877] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:119:0) after 1m0s
[09:00:03.955] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:119:0)
[09:00:03.955] Timer tick, asking Buildbucket for the build status
[09:00:04.193] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:04.193] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:120:0) after 1m0s
[09:01:04.247] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:120:0)
[09:01:04.247] Timer tick, asking Buildbucket for the build status
[09:01:04.615] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:121:0) after 1m0s
[09:02:04.646] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:121:0)
[09:02:04.646] Timer tick, asking Buildbucket for the build status
[09:02:05.350] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:05.350] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:122:0) after 1m0s
[09:03:05.417] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:122:0)
[09:03:05.417] Timer tick, asking Buildbucket for the build status
[09:03:05.779] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:05.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:123:0) after 1m0s
[09:04:05.914] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:123:0)
[09:04:05.914] Timer tick, asking Buildbucket for the build status
[09:04:06.780] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:06.780] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:124:0) after 1m0s
[09:05:06.885] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:124:0)
[09:05:06.885] Timer tick, asking Buildbucket for the build status
[09:05:08.397] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:08.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:125:0) after 1m0s
[09:06:08.528] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:125:0)
[09:06:08.528] Timer tick, asking Buildbucket for the build status
[09:06:08.697] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:08.697] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:126:0) after 1m0s
[09:07:08.743] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:126:0)
[09:07:08.743] Timer tick, asking Buildbucket for the build status
[09:07:09.085] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:09.085] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:127:0) after 1m0s
[09:08:09.238] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:127:0)
[09:08:09.238] Timer tick, asking Buildbucket for the build status
[09:08:09.351] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:09.351] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:128:0) after 1m0s
[09:09:09.345] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:128:0)
[09:09:09.345] Timer tick, asking Buildbucket for the build status
[09:09:09.470] Build 8876902683248704624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:09.470] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9042332745776636048:129:0) after 1m0s
[09:09:56.320] Received PubSub notification, asking Buildbucket for the build status
[09:09:56.445] Build 8876902683248704624: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:09:56.445] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1592730594941744",
"created_by": "project:chromium",
"created_ts": "1592722923955484",
"failure_reason": "BUILD_FAILURE",
"id": "8876902683248704624",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"388818e962ccee2a23783866f8e5eb93ac54ed79\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- ...129 more failure(s) (133 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- ...33 more failure(s) (35 total)...\\n\\n#### ...1 more test(s)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-4-q964\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 3469, \"got_angle_revision\": \"b772a955e70cee70b22c2170351476c7f865d577\", \"got_dawn_revision\": \"250f26229b27df7f19e013ca304843a666eb9f25\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"388818e962ccee2a23783866f8e5eb93ac54ed79\", \"got_revision_cp\": \"refs/heads/master@{#780617}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"ae1007957f096d015a1d48697a75c78d75d2f7b2\", \"got_v8_revision\": \"53beceea4b1b0ab459cdb32779a46386b90f3aaa\", \"got_v8_revision_cp\": \"refs/heads/8.5.189@{#1}\", \"got_webrtc_revision\": \"7a82467d0db0d61f466a1da54b94f6a136726a3c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31546}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"388818e962ccee2a23783866f8e5eb93ac54ed79\", \"swarm_hashes\": {\"android_browsertests\": \"0e2db7ea81ae74d087f4189b6d89f5ea492cee91\", \"chrome_public_test_apk\": \"0892ec39f2c7242e8db42bb54a39b86ed95932f0\", \"content_browsertests\": \"2876d4fbcfeaf413b06ea7b1961aaf191f6b5645\", \"content_shell_test_apk\": \"b48b6108f7466d2b812c4cb2cd2ebbcd106363b3\", \"content_unittests\": \"538b47859079e0ec87e15b030b38ee689b003bd6\", \"unit_tests\": \"1b50feaa1697155e8a2029c0344b90f7aa057125\"}, \"swarm_hashes_refs/heads/master(at){#780617}_without_patch\": {\"android_browsertests\": \"0e2db7ea81ae74d087f4189b6d89f5ea492cee91\", \"chrome_public_test_apk\": \"0892ec39f2c7242e8db42bb54a39b86ed95932f0\", \"content_browsertests\": \"2876d4fbcfeaf413b06ea7b1961aaf191f6b5645\", \"content_shell_test_apk\": \"b48b6108f7466d2b812c4cb2cd2ebbcd106363b3\", \"content_unittests\": \"538b47859079e0ec87e15b030b38ee689b003bd6\", \"unit_tests\": \"1b50feaa1697155e8a2029c0344b90f7aa057125\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0095fe006eafdabf54002a791fb8c836a288874203ece8347c24b74b8d090ce3_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_5c21095a1a129131be723eb555273fa4f1dc89f7e6d0ed0ea2c09e97b0cf5fcb_v2\", \"builder_94dc7d486ab1bafc5bfb11010ef18f0cf20e8e07ae86b58c09f57ba294c280ac_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-4-q964\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 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.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsHomepage__Instant_NoReturn\\n\\n- ...129 more failure(s) (133 total)...\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- ...33 more failure(s) (35 total)...\\n\\n#### ...1 more test(s)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1592724750907941",
"status": "COMPLETED",
"status_changed_ts": "1592724752348561",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/3469",
"builder:android-bfcache-rel",
"buildset:commit/git/388818e962ccee2a23783866f8e5eb93ac54ed79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/388818e962ccee2a23783866f8e5eb93ac54ed79",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042332745776636048",
"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/8876902683248704624/+/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:4cee80b0433edc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1592730595315248",
"url": "https://ci.chromium.org/b/8876902683248704624",
"utcnow_ts": "1592730596432125"
}
}
[09:09:56.445] Invocation finished in 2h7m54.284048162s with status FAILED