[08:13:06.036] New invocation is queued and will start shortly
[08:13:07.172] Starting the invocation (attempt 1)
[08:13:07.228] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:13:07.228] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:13:07.229] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020132018025806848",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"95fc11b56459394fe89299aa28565154051ba8ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9020132018025806848",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/95fc11b56459394fe89299aa28565154051ba8ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95fc11b56459394fe89299aa28565154051ba8ff",
"gitiles_ref:refs/heads/master"
]
}
[08:13:08.314] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613895187329841",
"id": "8854701956009764544",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95fc11b56459394fe89299aa28565154051ba8ff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613895187889389",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7927",
"builder:android-bfcache-rel",
"buildset:commit/git/95fc11b56459394fe89299aa28565154051ba8ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95fc11b56459394fe89299aa28565154051ba8ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020132018025806848",
"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/8854701956009764544/+/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": "1613895187889571",
"url": "https://ci.chromium.org/b/8854701956009764544",
"utcnow_ts": "1613895188301579"
}
}
[08:13:08.314] Task URL: https://ci.chromium.org/b/8854701956009764544
[08:13:08.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:2:0) after 1m0s
[08:14:08.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:2:0)
[08:14:08.375] Timer tick, asking Buildbucket for the build status
[08:14:08.468] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:08.468] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:3:0) after 1m0s
[08:15:08.584] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:3:0)
[08:15:08.584] Timer tick, asking Buildbucket for the build status
[08:15:08.741] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:08.741] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:4:0) after 1m0s
[08:16:08.840] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:4:0)
[08:16:08.840] Timer tick, asking Buildbucket for the build status
[08:16:08.944] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:08.944] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:5:0) after 1m0s
[08:17:08.991] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:5:0)
[08:17:08.991] Timer tick, asking Buildbucket for the build status
[08:17:09.057] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:09.057] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:6:0) after 1m0s
[08:18:09.145] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:6:0)
[08:18:09.145] Timer tick, asking Buildbucket for the build status
[08:18:09.246] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:09.246] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:7:0) after 1m0s
[08:19:09.356] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:7:0)
[08:19:09.356] Timer tick, asking Buildbucket for the build status
[08:19:09.598] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:09.598] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:8:0) after 1m0s
[08:20:09.641] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:8:0)
[08:20:09.641] Timer tick, asking Buildbucket for the build status
[08:20:09.741] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:09.741] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:9:0) after 1m0s
[08:21:09.755] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:9:0)
[08:21:09.755] Timer tick, asking Buildbucket for the build status
[08:21:10.350] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:10.350] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:10:0) after 1m0s
[08:22:10.399] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:10:0)
[08:22:10.399] Timer tick, asking Buildbucket for the build status
[08:22:10.475] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:10.475] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:11:0) after 1m0s
[08:23:10.519] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:11:0)
[08:23:10.519] Timer tick, asking Buildbucket for the build status
[08:23:10.627] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:10.627] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:12:0) after 1m0s
[08:24:10.701] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:12:0)
[08:24:10.701] Timer tick, asking Buildbucket for the build status
[08:24:10.805] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:10.805] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:13:0) after 1m0s
[08:25:10.884] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:13:0)
[08:25:10.884] Timer tick, asking Buildbucket for the build status
[08:25:11.014] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:11.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:14:0) after 1m0s
[08:26:11.103] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:14:0)
[08:26:11.103] Timer tick, asking Buildbucket for the build status
[08:26:11.197] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:11.197] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:15:0) after 1m0s
[08:27:11.277] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:15:0)
[08:27:11.277] Timer tick, asking Buildbucket for the build status
[08:27:11.339] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:11.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:16:0) after 1m0s
[08:28:11.359] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:16:0)
[08:28:11.359] Timer tick, asking Buildbucket for the build status
[08:28:11.900] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:11.900] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:17:0) after 1m0s
[08:29:11.921] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:17:0)
[08:29:11.921] Timer tick, asking Buildbucket for the build status
[08:29:11.982] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:11.982] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:18:0) after 1m0s
[08:30:12.006] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:18:0)
[08:30:12.006] Timer tick, asking Buildbucket for the build status
[08:30:12.308] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:12.308] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:19:0) after 1m0s
[08:31:12.358] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:19:0)
[08:31:12.358] Timer tick, asking Buildbucket for the build status
[08:31:12.494] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:12.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:20:0) after 1m0s
[08:32:12.512] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:20:0)
[08:32:12.512] Timer tick, asking Buildbucket for the build status
[08:32:12.578] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:12.578] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:21:0) after 1m0s
[08:33:12.594] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:21:0)
[08:33:12.594] Timer tick, asking Buildbucket for the build status
[08:33:12.824] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:12.824] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:22:0) after 1m0s
[08:34:12.792] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:22:0)
[08:34:12.792] Timer tick, asking Buildbucket for the build status
[08:34:12.864] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:12.864] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:23:0) after 1m0s
[08:35:12.918] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:23:0)
[08:35:12.918] Timer tick, asking Buildbucket for the build status
[08:35:13.054] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:13.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:24:0) after 1m0s
[08:36:13.078] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:24:0)
[08:36:13.078] Timer tick, asking Buildbucket for the build status
[08:36:13.149] Build 8854701956009764544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:13.149] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:25:0) after 1m0s
[08:36:37.069] Received PubSub notification, asking Buildbucket for the build status
[08:36:38.817] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:13.181] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:25:0)
[08:37:13.181] Timer tick, asking Buildbucket for the build status
[08:37:13.424] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:13.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:27:0) after 1m0s
[08:38:13.454] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:27:0)
[08:38:13.454] Timer tick, asking Buildbucket for the build status
[08:38:13.821] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:13.821] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:28:0) after 1m0s
[08:39:13.883] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:28:0)
[08:39:13.883] Timer tick, asking Buildbucket for the build status
[08:39:13.966] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:13.966] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:29:0) after 1m0s
[08:40:14.000] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:29:0)
[08:40:14.000] Timer tick, asking Buildbucket for the build status
[08:40:14.184] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:14.184] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:30:0) after 1m0s
[08:41:14.236] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:30:0)
[08:41:14.236] Timer tick, asking Buildbucket for the build status
[08:41:14.303] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:14.303] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:31:0) after 1m0s
[08:42:14.372] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:31:0)
[08:42:14.372] Timer tick, asking Buildbucket for the build status
[08:42:14.440] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:14.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:32:0) after 1m0s
[08:43:14.433] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:32:0)
[08:43:14.433] Timer tick, asking Buildbucket for the build status
[08:43:14.648] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:14.648] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:33:0) after 1m0s
[08:44:14.734] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:33:0)
[08:44:14.734] Timer tick, asking Buildbucket for the build status
[08:44:14.931] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:34:0) after 1m0s
[08:45:14.912] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:34:0)
[08:45:14.912] Timer tick, asking Buildbucket for the build status
[08:45:15.091] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:15.091] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:35:0) after 1m0s
[08:46:15.137] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:35:0)
[08:46:15.137] Timer tick, asking Buildbucket for the build status
[08:46:15.214] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:15.214] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:36:0) after 1m0s
[08:47:15.213] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:36:0)
[08:47:15.213] Timer tick, asking Buildbucket for the build status
[08:47:15.295] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:15.295] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:37:0) after 1m0s
[08:48:15.352] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:37:0)
[08:48:15.352] Timer tick, asking Buildbucket for the build status
[08:48:15.450] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:15.450] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:38:0) after 1m0s
[08:49:15.522] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:38:0)
[08:49:15.522] Timer tick, asking Buildbucket for the build status
[08:49:15.592] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:15.592] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:39:0) after 1m0s
[08:50:15.671] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:39:0)
[08:50:15.671] Timer tick, asking Buildbucket for the build status
[08:50:15.752] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:15.752] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:40:0) after 1m0s
[08:51:15.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:40:0)
[08:51:15.770] Timer tick, asking Buildbucket for the build status
[08:51:15.848] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:41:0) after 1m0s
[08:52:15.865] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:41:0)
[08:52:15.865] Timer tick, asking Buildbucket for the build status
[08:52:16.129] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:16.129] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:42:0) after 1m0s
[08:53:16.143] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:42:0)
[08:53:16.143] Timer tick, asking Buildbucket for the build status
[08:53:16.320] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:16.320] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:43:0) after 1m0s
[08:54:16.323] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:43:0)
[08:54:16.323] Timer tick, asking Buildbucket for the build status
[08:54:16.419] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:16.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:44:0) after 1m0s
[08:55:16.462] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:44:0)
[08:55:16.462] Timer tick, asking Buildbucket for the build status
[08:55:16.614] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:16.614] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:45:0) after 1m0s
[08:56:16.672] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:45:0)
[08:56:16.672] Timer tick, asking Buildbucket for the build status
[08:56:16.762] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:16.762] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:46:0) after 1m0s
[08:57:16.822] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:46:0)
[08:57:16.822] Timer tick, asking Buildbucket for the build status
[08:57:16.888] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.889] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:47:0) after 1m0s
[08:58:16.995] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:47:0)
[08:58:16.995] Timer tick, asking Buildbucket for the build status
[08:58:17.103] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:17.103] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:48:0) after 1m0s
[08:59:17.216] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:48:0)
[08:59:17.216] Timer tick, asking Buildbucket for the build status
[08:59:17.282] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:17.282] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:49:0) after 1m0s
[09:00:17.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:49:0)
[09:00:17.385] Timer tick, asking Buildbucket for the build status
[09:00:17.475] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:17.475] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:50:0) after 1m0s
[09:01:18.502] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:50:0)
[09:01:18.502] Timer tick, asking Buildbucket for the build status
[09:01:18.571] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.571] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:51:0) after 1m0s
[09:02:18.573] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:51:0)
[09:02:18.574] Timer tick, asking Buildbucket for the build status
[09:02:18.637] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.637] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:52:0) after 1m0s
[09:03:18.660] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:52:0)
[09:03:18.660] Timer tick, asking Buildbucket for the build status
[09:03:19.006] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:19.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:53:0) after 1m0s
[09:04:19.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:53:0)
[09:04:19.021] Timer tick, asking Buildbucket for the build status
[09:04:19.276] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:19.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:54:0) after 1m0s
[09:05:19.305] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:54:0)
[09:05:19.305] Timer tick, asking Buildbucket for the build status
[09:05:19.383] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:19.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:55:0) after 1m0s
[09:06:19.500] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:55:0)
[09:06:19.500] Timer tick, asking Buildbucket for the build status
[09:06:19.560] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:19.560] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:56:0) after 1m0s
[09:07:19.599] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:56:0)
[09:07:19.599] Timer tick, asking Buildbucket for the build status
[09:07:19.703] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.703] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:57:0) after 1m0s
[09:08:19.737] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:57:0)
[09:08:19.737] Timer tick, asking Buildbucket for the build status
[09:08:20.121] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:20.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:58:0) after 1m0s
[09:09:20.154] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:58:0)
[09:09:20.154] Timer tick, asking Buildbucket for the build status
[09:09:20.257] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:20.257] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:59:0) after 1m0s
[09:10:20.293] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:59:0)
[09:10:20.293] Timer tick, asking Buildbucket for the build status
[09:10:20.417] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:60:0) after 1m0s
[09:11:20.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:60:0)
[09:11:20.437] Timer tick, asking Buildbucket for the build status
[09:11:20.506] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:20.506] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:61:0) after 1m0s
[09:12:20.532] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:61:0)
[09:12:20.532] Timer tick, asking Buildbucket for the build status
[09:12:20.736] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:20.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:62:0) after 1m0s
[09:13:20.724] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:62:0)
[09:13:20.724] Timer tick, asking Buildbucket for the build status
[09:13:20.828] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:20.828] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:63:0) after 1m0s
[09:14:20.847] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:63:0)
[09:14:20.847] Timer tick, asking Buildbucket for the build status
[09:14:20.919] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:64:0) after 1m0s
[09:15:20.971] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:64:0)
[09:15:20.971] Timer tick, asking Buildbucket for the build status
[09:15:21.045] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:21.045] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:65:0) after 1m0s
[09:16:21.056] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:65:0)
[09:16:21.056] Timer tick, asking Buildbucket for the build status
[09:16:21.212] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:21.212] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:66:0) after 1m0s
[09:17:21.277] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:66:0)
[09:17:21.277] Timer tick, asking Buildbucket for the build status
[09:17:21.388] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:21.388] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:67:0) after 1m0s
[09:18:21.427] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:67:0)
[09:18:21.427] Timer tick, asking Buildbucket for the build status
[09:18:21.597] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:68:0) after 1m0s
[09:19:21.664] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:68:0)
[09:19:21.664] Timer tick, asking Buildbucket for the build status
[09:19:21.800] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:21.800] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:69:0) after 1m0s
[09:20:21.822] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:69:0)
[09:20:21.822] Timer tick, asking Buildbucket for the build status
[09:20:21.903] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:70:0) after 1m0s
[09:21:21.931] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:70:0)
[09:21:21.931] Timer tick, asking Buildbucket for the build status
[09:21:21.996] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:21.996] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:71:0) after 1m0s
[09:22:22.073] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:71:0)
[09:22:22.074] Timer tick, asking Buildbucket for the build status
[09:22:22.155] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:72:0) after 1m0s
[09:23:22.174] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:72:0)
[09:23:22.174] Timer tick, asking Buildbucket for the build status
[09:23:22.269] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:22.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:73:0) after 1m0s
[09:24:22.329] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:73:0)
[09:24:22.329] Timer tick, asking Buildbucket for the build status
[09:24:22.447] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:22.447] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:74:0) after 1m0s
[09:25:22.466] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:74:0)
[09:25:22.466] Timer tick, asking Buildbucket for the build status
[09:25:22.544] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:22.544] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:75:0) after 1m0s
[09:26:22.605] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:75:0)
[09:26:22.605] Timer tick, asking Buildbucket for the build status
[09:26:22.729] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:22.729] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:76:0) after 1m0s
[09:27:22.744] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:76:0)
[09:27:22.744] Timer tick, asking Buildbucket for the build status
[09:27:22.818] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:22.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:77:0) after 1m0s
[09:28:22.867] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:77:0)
[09:28:22.867] Timer tick, asking Buildbucket for the build status
[09:28:22.951] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:22.952] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:78:0) after 1m0s
[09:29:22.929] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:78:0)
[09:29:22.929] Timer tick, asking Buildbucket for the build status
[09:29:23.020] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:23.020] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:79:0) after 1m0s
[09:30:23.060] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:79:0)
[09:30:23.060] Timer tick, asking Buildbucket for the build status
[09:30:23.120] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:23.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:80:0) after 1m0s
[09:31:23.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:80:0)
[09:31:23.153] Timer tick, asking Buildbucket for the build status
[09:31:23.434] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:23.434] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:81:0) after 1m0s
[09:32:23.470] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:81:0)
[09:32:23.470] Timer tick, asking Buildbucket for the build status
[09:32:23.542] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:23.542] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:82:0) after 1m0s
[09:33:23.571] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:82:0)
[09:33:23.571] Timer tick, asking Buildbucket for the build status
[09:33:23.667] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:23.667] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:83:0) after 1m0s
[09:34:23.687] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:83:0)
[09:34:23.687] Timer tick, asking Buildbucket for the build status
[09:34:23.770] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:23.770] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:84:0) after 1m0s
[09:35:23.804] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:84:0)
[09:35:23.804] Timer tick, asking Buildbucket for the build status
[09:35:23.896] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:23.896] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:85:0) after 1m0s
[09:36:23.952] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:85:0)
[09:36:23.952] Timer tick, asking Buildbucket for the build status
[09:36:24.149] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:24.149] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:86:0) after 1m0s
[09:37:24.132] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:86:0)
[09:37:24.132] Timer tick, asking Buildbucket for the build status
[09:37:24.280] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:24.280] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:87:0) after 1m0s
[09:38:24.349] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:87:0)
[09:38:24.349] Timer tick, asking Buildbucket for the build status
[09:38:24.483] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:24.483] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:88:0) after 1m0s
[09:39:24.502] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:88:0)
[09:39:24.502] Timer tick, asking Buildbucket for the build status
[09:39:24.649] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:24.649] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:89:0) after 1m0s
[09:40:24.672] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:89:0)
[09:40:24.672] Timer tick, asking Buildbucket for the build status
[09:40:24.932] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:24.932] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:90:0) after 1m0s
[09:41:25.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:90:0)
[09:41:25.034] Timer tick, asking Buildbucket for the build status
[09:41:25.209] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.209] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:91:0) after 1m0s
[09:42:25.228] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:91:0)
[09:42:25.228] Timer tick, asking Buildbucket for the build status
[09:42:25.294] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:25.294] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:92:0) after 1m0s
[09:43:25.378] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:92:0)
[09:43:25.379] Timer tick, asking Buildbucket for the build status
[09:43:25.537] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:25.537] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:93:0) after 1m0s
[09:44:25.559] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:93:0)
[09:44:25.559] Timer tick, asking Buildbucket for the build status
[09:44:25.840] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:25.840] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:94:0) after 1m0s
[09:45:25.964] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:94:0)
[09:45:25.964] Timer tick, asking Buildbucket for the build status
[09:45:26.039] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:26.039] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:95:0) after 1m0s
[09:46:26.133] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:95:0)
[09:46:26.133] Timer tick, asking Buildbucket for the build status
[09:46:26.223] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:26.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:96:0) after 1m0s
[09:47:26.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:96:0)
[09:47:26.303] Timer tick, asking Buildbucket for the build status
[09:47:26.387] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:26.387] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:97:0) after 1m0s
[09:48:26.362] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:97:0)
[09:48:26.362] Timer tick, asking Buildbucket for the build status
[09:48:26.459] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:26.459] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:98:0) after 1m0s
[09:49:26.476] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:98:0)
[09:49:26.476] Timer tick, asking Buildbucket for the build status
[09:49:26.755] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:26.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:99:0) after 1m0s
[09:50:26.781] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:99:0)
[09:50:26.781] Timer tick, asking Buildbucket for the build status
[09:50:26.876] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:26.876] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:100:0) after 1m0s
[09:51:27.026] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:100:0)
[09:51:27.026] Timer tick, asking Buildbucket for the build status
[09:51:27.108] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:27.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:101:0) after 1m0s
[09:52:27.226] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:101:0)
[09:52:27.226] Timer tick, asking Buildbucket for the build status
[09:52:27.424] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:27.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:102:0) after 1m0s
[09:53:27.578] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:102:0)
[09:53:27.578] Timer tick, asking Buildbucket for the build status
[09:53:27.947] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:103:0) after 1m0s
[09:54:28.130] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:103:0)
[09:54:28.130] Timer tick, asking Buildbucket for the build status
[09:54:28.229] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:28.229] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:104:0) after 1m0s
[09:55:28.300] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:104:0)
[09:55:28.300] Timer tick, asking Buildbucket for the build status
[09:55:28.370] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:28.370] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:105:0) after 1m0s
[09:56:29.464] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:105:0)
[09:56:29.464] Timer tick, asking Buildbucket for the build status
[09:56:29.549] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:106:0) after 1m0s
[09:57:29.673] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:106:0)
[09:57:29.673] Timer tick, asking Buildbucket for the build status
[09:57:30.821] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:30.821] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:107:0) after 1m0s
[09:58:31.121] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:107:0)
[09:58:31.121] Timer tick, asking Buildbucket for the build status
[09:58:31.212] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:31.212] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:108:0) after 1m0s
[09:59:31.322] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:108:0)
[09:59:31.322] Timer tick, asking Buildbucket for the build status
[09:59:31.624] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:31.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:109:0) after 1m0s
[10:00:31.783] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:109:0)
[10:00:31.783] Timer tick, asking Buildbucket for the build status
[10:00:31.863] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:31.863] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:110:0) after 1m0s
[10:01:31.876] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:110:0)
[10:01:31.876] Timer tick, asking Buildbucket for the build status
[10:01:32.470] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:32.470] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:111:0) after 1m0s
[10:02:32.511] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:111:0)
[10:02:32.511] Timer tick, asking Buildbucket for the build status
[10:02:32.738] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:32.738] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:112:0) after 1m0s
[10:03:32.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:112:0)
[10:03:32.869] Timer tick, asking Buildbucket for the build status
[10:03:32.975] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:32.975] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:113:0) after 1m0s
[10:04:33.064] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:113:0)
[10:04:33.065] Timer tick, asking Buildbucket for the build status
[10:04:33.136] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:33.136] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:114:0) after 1m0s
[10:05:33.246] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:114:0)
[10:05:33.246] Timer tick, asking Buildbucket for the build status
[10:05:33.332] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:33.332] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:115:0) after 1m0s
[10:06:33.453] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:115:0)
[10:06:33.453] Timer tick, asking Buildbucket for the build status
[10:06:33.522] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:33.522] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:116:0) after 1m0s
[10:07:33.569] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:116:0)
[10:07:33.569] Timer tick, asking Buildbucket for the build status
[10:07:33.687] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:33.687] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:117:0) after 1m0s
[10:08:33.810] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:117:0)
[10:08:33.810] Timer tick, asking Buildbucket for the build status
[10:08:34.096] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:34.096] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:118:0) after 1m0s
[10:09:34.127] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:118:0)
[10:09:34.127] Timer tick, asking Buildbucket for the build status
[10:09:34.210] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:34.210] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:119:0) after 1m0s
[10:10:34.214] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:119:0)
[10:10:34.214] Timer tick, asking Buildbucket for the build status
[10:10:34.292] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:34.292] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:120:0) after 1m0s
[10:11:34.310] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:120:0)
[10:11:34.310] Timer tick, asking Buildbucket for the build status
[10:11:34.444] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:34.444] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:121:0) after 1m0s
[10:12:34.501] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:121:0)
[10:12:34.501] Timer tick, asking Buildbucket for the build status
[10:12:34.597] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:34.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:122:0) after 1m0s
[10:13:34.619] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:122:0)
[10:13:34.619] Timer tick, asking Buildbucket for the build status
[10:13:34.851] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:123:0) after 1m0s
[10:14:34.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:123:0)
[10:14:34.869] Timer tick, asking Buildbucket for the build status
[10:14:34.943] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:34.943] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:124:0) after 1m0s
[10:15:34.963] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:124:0)
[10:15:34.963] Timer tick, asking Buildbucket for the build status
[10:15:35.042] Build 8854701956009764544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:35.042] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020132018025806848:125:0) after 1m0s
[10:16:00.563] Received PubSub notification, asking Buildbucket for the build status
[10:16:00.633] Build 8854701956009764544: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:16:00.633] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613902560206339",
"created_by": "project:chromium",
"created_ts": "1613895187329841",
"failure_reason": "BUILD_FAILURE",
"id": "8854701956009764544",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95fc11b56459394fe89299aa28565154051ba8ff\"}}",
"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.tasks.tab_management.ConditionalTabStripTest#testStrip_ContinuousDismissCounter\"}, \"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\": \"95fc11b56459394fe89299aa28565154051ba8ff\", \"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-91-j2xe\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7927, \"got_angle_revision\": \"6e8cdd39b19912e7fe6408b0318d0907dc7e4d21\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"95fc11b56459394fe89299aa28565154051ba8ff\", \"got_revision_cp\": \"refs/heads/master@{#856136}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"95fc11b56459394fe89299aa28565154051ba8ff\", \"swarm_hashes_refs/heads/master(at){#856136}_without_patch\": {\"android_browsertests\": \"7e0f8094deee55fc3674ec642bb0a55bf970004f\", \"chrome_public_test_apk\": \"5f1a2fe59bf7e61f1d824e630adeefc36afbba63\", \"content_browsertests\": \"da9992492cd7c524a662778823e3016be27f418c\", \"content_shell_test_apk\": \"8705195a8c783eb17cff6918954b0f0455d90177\", \"content_unittests\": \"6306195258d7b3f95572919a6fb45840ede1ec7a\", \"unit_tests\": \"72abdaea5d1f175c33987c374bc27472dbc829e6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_7f949073f7e904bb9563d7870e146ee8e8e8f26011cb03330fcb60d1fc06c798_v2\", \"builder_859368e171259d521efea13da6023dc28f5117d74d565299fe323cf1e9b3ffc9_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_d7f946c7be1ca4532a0b589984b71d0ccde82b752a717d972913778e4d93c76d_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-91-j2xe\"], \"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\": [\"5706-397711c\"], \"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.tasks.tab_management.ConditionalTabStripTest#testStrip_ContinuousDismissCounter\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613896596404987",
"status": "COMPLETED",
"status_changed_ts": "1613896596917070",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7927",
"builder:android-bfcache-rel",
"buildset:commit/git/95fc11b56459394fe89299aa28565154051ba8ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95fc11b56459394fe89299aa28565154051ba8ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020132018025806848",
"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/8854701956009764544/+/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:51dc77c740036310",
"user_agent:luci-scheduler"
],
"updated_ts": "1613902560295845",
"url": "https://ci.chromium.org/b/8854701956009764544",
"utcnow_ts": "1613902560627042"
}
}
[10:16:00.633] Invocation finished in 2h2m54.610118241s with status FAILED