[06:05:52.808] New invocation is queued and will start shortly
[06:05:53.958] Starting the invocation (attempt 1)
[06:05:54.057] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:05:54.057] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:05:54.057] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030739867120736400",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"596d5c36bb1df7bac9d12fc0684478acdebd9b91\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9030739867120736400",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"gitiles_ref:refs/heads/master"
]
}
[06:05:54.670] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603778754205875",
"id": "8865309804989051456",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"596d5c36bb1df7bac9d12fc0684478acdebd9b91\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603778754513656",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5759",
"builder:android-bfcache-rel",
"buildset:commit/git/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030739867120736400",
"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/8865309804989051456/+/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": "1603778754513775",
"url": "https://ci.chromium.org/b/8865309804989051456",
"utcnow_ts": "1603778754661233"
}
}
[06:05:54.670] Task URL: https://ci.chromium.org/b/8865309804989051456
[06:05:54.670] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:2:0) after 1m0s
[06:06:00.601] Received PubSub notification, asking Buildbucket for the build status
[06:06:00.759] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:54.708] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:2:0)
[06:06:54.708] Timer tick, asking Buildbucket for the build status
[06:06:54.850] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:54.850] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:4:0) after 1m0s
[06:07:54.908] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:4:0)
[06:07:54.908] Timer tick, asking Buildbucket for the build status
[06:07:55.093] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:55.093] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:5:0) after 1m0s
[06:08:55.102] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:5:0)
[06:08:55.102] Timer tick, asking Buildbucket for the build status
[06:08:55.251] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:55.251] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:6:0) after 1m0s
[06:09:55.244] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:6:0)
[06:09:55.244] Timer tick, asking Buildbucket for the build status
[06:09:57.054] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:7:0) after 1m0s
[06:10:57.081] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:7:0)
[06:10:57.081] Timer tick, asking Buildbucket for the build status
[06:10:57.257] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:57.257] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:8:0) after 1m0s
[06:11:57.287] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:8:0)
[06:11:57.287] Timer tick, asking Buildbucket for the build status
[06:11:57.379] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:57.379] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:9:0) after 1m0s
[06:12:57.389] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:9:0)
[06:12:57.389] Timer tick, asking Buildbucket for the build status
[06:12:57.473] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:57.473] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:10:0) after 1m0s
[06:13:57.497] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:10:0)
[06:13:57.497] Timer tick, asking Buildbucket for the build status
[06:13:57.561] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:57.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:11:0) after 1m0s
[06:14:57.553] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:11:0)
[06:14:57.553] Timer tick, asking Buildbucket for the build status
[06:14:57.783] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:57.784] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:12:0) after 1m0s
[06:15:57.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:12:0)
[06:15:57.791] Timer tick, asking Buildbucket for the build status
[06:15:57.969] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:57.969] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:13:0) after 1m0s
[06:16:57.996] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:13:0)
[06:16:57.996] Timer tick, asking Buildbucket for the build status
[06:16:58.084] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:58.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:14:0) after 1m0s
[06:17:58.171] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:14:0)
[06:17:58.171] Timer tick, asking Buildbucket for the build status
[06:17:58.242] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:58.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:15:0) after 1m0s
[06:18:58.265] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:15:0)
[06:18:58.265] Timer tick, asking Buildbucket for the build status
[06:18:58.333] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:58.333] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:16:0) after 1m0s
[06:19:58.371] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:16:0)
[06:19:58.371] Timer tick, asking Buildbucket for the build status
[06:19:58.470] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:58.470] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:17:0) after 1m0s
[06:20:58.573] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:17:0)
[06:20:58.573] Timer tick, asking Buildbucket for the build status
[06:20:58.837] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:58.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:18:0) after 1m0s
[06:21:58.837] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:18:0)
[06:21:58.837] Timer tick, asking Buildbucket for the build status
[06:21:58.995] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:58.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:19:0) after 1m0s
[06:22:59.027] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:19:0)
[06:22:59.027] Timer tick, asking Buildbucket for the build status
[06:22:59.189] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:59.190] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:20:0) after 1m0s
[06:23:59.214] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:20:0)
[06:23:59.214] Timer tick, asking Buildbucket for the build status
[06:23:59.439] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:59.439] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:21:0) after 1m0s
[06:24:59.451] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:21:0)
[06:24:59.452] Timer tick, asking Buildbucket for the build status
[06:24:59.555] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:59.555] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:22:0) after 1m0s
[06:25:59.542] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:22:0)
[06:25:59.542] Timer tick, asking Buildbucket for the build status
[06:25:59.687] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:59.687] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:23:0) after 1m0s
[06:26:59.709] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:23:0)
[06:26:59.709] Timer tick, asking Buildbucket for the build status
[06:26:59.920] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:59.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:24:0) after 1m0s
[06:28:00.009] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:24:0)
[06:28:00.009] Timer tick, asking Buildbucket for the build status
[06:28:00.292] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:00.292] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:25:0) after 1m0s
[06:29:00.343] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:25:0)
[06:29:00.343] Timer tick, asking Buildbucket for the build status
[06:29:00.457] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:00.457] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:26:0) after 1m0s
[06:30:00.478] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:26:0)
[06:30:00.478] Timer tick, asking Buildbucket for the build status
[06:30:00.653] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:00.653] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:27:0) after 1m0s
[06:31:01.054] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:27:0)
[06:31:01.054] Timer tick, asking Buildbucket for the build status
[06:31:01.451] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:28:0) after 1m0s
[06:32:01.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:28:0)
[06:32:01.477] Timer tick, asking Buildbucket for the build status
[06:32:01.584] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:01.584] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:29:0) after 1m0s
[06:33:01.616] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:29:0)
[06:33:01.616] Timer tick, asking Buildbucket for the build status
[06:33:01.759] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:01.759] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:30:0) after 1m0s
[06:34:01.821] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:30:0)
[06:34:01.821] Timer tick, asking Buildbucket for the build status
[06:34:01.956] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:01.956] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:31:0) after 1m0s
[06:35:01.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:31:0)
[06:35:01.978] Timer tick, asking Buildbucket for the build status
[06:35:02.223] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:02.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:32:0) after 1m0s
[06:36:02.328] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:32:0)
[06:36:02.328] Timer tick, asking Buildbucket for the build status
[06:36:02.911] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:02.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:33:0) after 1m0s
[06:37:02.931] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:33:0)
[06:37:02.931] Timer tick, asking Buildbucket for the build status
[06:37:03.038] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:03.038] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:34:0) after 1m0s
[06:38:03.098] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:34:0)
[06:38:03.098] Timer tick, asking Buildbucket for the build status
[06:38:03.194] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:03.194] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:35:0) after 1m0s
[06:39:03.241] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:35:0)
[06:39:03.241] Timer tick, asking Buildbucket for the build status
[06:39:03.474] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.474] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:36:0) after 1m0s
[06:40:03.517] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:36:0)
[06:40:03.517] Timer tick, asking Buildbucket for the build status
[06:40:03.639] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:03.639] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:37:0) after 1m0s
[06:41:03.753] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:37:0)
[06:41:03.753] Timer tick, asking Buildbucket for the build status
[06:41:03.882] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:03.882] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:38:0) after 1m0s
[06:42:04.067] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:38:0)
[06:42:04.067] Timer tick, asking Buildbucket for the build status
[06:42:04.132] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:04.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:39:0) after 1m0s
[06:43:04.255] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:39:0)
[06:43:04.255] Timer tick, asking Buildbucket for the build status
[06:43:04.323] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:04.323] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:40:0) after 1m0s
[06:44:04.484] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:40:0)
[06:44:04.484] Timer tick, asking Buildbucket for the build status
[06:44:04.548] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:04.548] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:41:0) after 1m0s
[06:45:04.842] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:41:0)
[06:45:04.842] Timer tick, asking Buildbucket for the build status
[06:45:04.929] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:04.929] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:42:0) after 1m0s
[06:46:05.296] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:42:0)
[06:46:05.296] Timer tick, asking Buildbucket for the build status
[06:46:05.443] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:05.443] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:43:0) after 1m0s
[06:47:05.500] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:43:0)
[06:47:05.500] Timer tick, asking Buildbucket for the build status
[06:47:05.620] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:05.620] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:44:0) after 1m0s
[06:48:05.666] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:44:0)
[06:48:05.666] Timer tick, asking Buildbucket for the build status
[06:48:05.844] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:05.844] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:45:0) after 1m0s
[06:49:06.265] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:45:0)
[06:49:06.265] Timer tick, asking Buildbucket for the build status
[06:49:06.401] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:06.401] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:46:0) after 1m0s
[06:50:06.777] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:46:0)
[06:50:06.777] Timer tick, asking Buildbucket for the build status
[06:50:06.904] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:06.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:47:0) after 1m0s
[06:51:07.526] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:47:0)
[06:51:07.526] Timer tick, asking Buildbucket for the build status
[06:51:07.691] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:07.691] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:48:0) after 1m0s
[06:52:07.903] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:48:0)
[06:52:07.903] Timer tick, asking Buildbucket for the build status
[06:52:07.984] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:07.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:49:0) after 1m0s
[06:53:08.171] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:49:0)
[06:53:08.171] Timer tick, asking Buildbucket for the build status
[06:53:08.243] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.243] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:50:0) after 1m0s
[06:54:08.429] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:50:0)
[06:54:08.429] Timer tick, asking Buildbucket for the build status
[06:54:08.513] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.513] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:51:0) after 1m0s
[06:55:08.579] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:51:0)
[06:55:08.579] Timer tick, asking Buildbucket for the build status
[06:55:08.827] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:08.827] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:52:0) after 1m0s
[06:56:08.939] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:52:0)
[06:56:08.939] Timer tick, asking Buildbucket for the build status
[06:56:09.098] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:09.098] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:53:0) after 1m0s
[06:57:09.166] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:53:0)
[06:57:09.166] Timer tick, asking Buildbucket for the build status
[06:57:09.437] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:09.437] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:54:0) after 1m0s
[06:58:09.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:54:0)
[06:58:09.493] Timer tick, asking Buildbucket for the build status
[06:58:09.566] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:09.566] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:55:0) after 1m0s
[06:59:09.596] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:55:0)
[06:59:09.596] Timer tick, asking Buildbucket for the build status
[06:59:09.675] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:09.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:56:0) after 1m0s
[07:00:09.721] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:56:0)
[07:00:09.721] Timer tick, asking Buildbucket for the build status
[07:00:09.981] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:09.982] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:57:0) after 1m0s
[07:01:10.189] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:57:0)
[07:01:10.189] Timer tick, asking Buildbucket for the build status
[07:01:10.458] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:10.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:58:0) after 1m0s
[07:02:10.828] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:58:0)
[07:02:10.828] Timer tick, asking Buildbucket for the build status
[07:02:11.106] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:11.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:59:0) after 1m0s
[07:03:11.153] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:59:0)
[07:03:11.153] Timer tick, asking Buildbucket for the build status
[07:03:11.341] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.341] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:60:0) after 1m0s
[07:04:11.446] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:60:0)
[07:04:11.446] Timer tick, asking Buildbucket for the build status
[07:04:11.729] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:11.729] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:61:0) after 1m0s
[07:05:11.735] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:61:0)
[07:05:11.735] Timer tick, asking Buildbucket for the build status
[07:05:11.878] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:11.878] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:62:0) after 1m0s
[07:06:11.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:62:0)
[07:06:11.946] Timer tick, asking Buildbucket for the build status
[07:06:12.174] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:12.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:63:0) after 1m0s
[07:07:12.245] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:63:0)
[07:07:12.245] Timer tick, asking Buildbucket for the build status
[07:07:12.382] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:64:0) after 1m0s
[07:08:12.415] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:64:0)
[07:08:12.415] Timer tick, asking Buildbucket for the build status
[07:08:12.550] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.550] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:65:0) after 1m0s
[07:09:12.614] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:65:0)
[07:09:12.614] Timer tick, asking Buildbucket for the build status
[07:09:12.833] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:66:0) after 1m0s
[07:10:12.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:66:0)
[07:10:12.978] Timer tick, asking Buildbucket for the build status
[07:10:13.205] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.205] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:67:0) after 1m0s
[07:11:13.283] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:67:0)
[07:11:13.283] Timer tick, asking Buildbucket for the build status
[07:11:13.417] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:13.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:68:0) after 1m0s
[07:12:13.482] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:68:0)
[07:12:13.482] Timer tick, asking Buildbucket for the build status
[07:12:13.582] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:13.582] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:69:0) after 1m0s
[07:13:13.614] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:69:0)
[07:13:13.614] Timer tick, asking Buildbucket for the build status
[07:13:13.884] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:13.884] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:70:0) after 1m0s
[07:14:13.918] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:70:0)
[07:14:13.918] Timer tick, asking Buildbucket for the build status
[07:14:14.392] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:14.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:71:0) after 1m0s
[07:15:14.421] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:71:0)
[07:15:14.421] Timer tick, asking Buildbucket for the build status
[07:15:14.698] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:14.698] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:72:0) after 1m0s
[07:16:15.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:72:0)
[07:16:15.034] Timer tick, asking Buildbucket for the build status
[07:16:15.145] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:15.145] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:73:0) after 1m0s
[07:17:15.329] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:73:0)
[07:17:15.329] Timer tick, asking Buildbucket for the build status
[07:17:15.486] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:15.486] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:74:0) after 1m0s
[07:18:15.638] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:74:0)
[07:18:15.638] Timer tick, asking Buildbucket for the build status
[07:18:15.836] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:15.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:75:0) after 1m0s
[07:19:15.920] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:75:0)
[07:19:15.920] Timer tick, asking Buildbucket for the build status
[07:19:16.136] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:16.136] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:76:0) after 1m0s
[07:20:16.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:76:0)
[07:20:16.206] Timer tick, asking Buildbucket for the build status
[07:20:16.267] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:77:0) after 1m0s
[07:21:16.422] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:77:0)
[07:21:16.422] Timer tick, asking Buildbucket for the build status
[07:21:16.597] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:16.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:78:0) after 1m0s
[07:22:16.676] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:78:0)
[07:22:16.676] Timer tick, asking Buildbucket for the build status
[07:22:16.761] Build 8865309804989051456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:16.761] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9030739867120736400:79:0) after 1m0s
[07:23:01.359] Received PubSub notification, asking Buildbucket for the build status
[07:23:01.471] Build 8865309804989051456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:23:01.471] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603783380629717",
"created_by": "project:chromium",
"created_ts": "1603778754205875",
"failure_reason": "BUILD_FAILURE",
"id": "8865309804989051456",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"596d5c36bb1df7bac9d12fc0684478acdebd9b91\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** 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_InfoBarOptIn\"}, \"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\": \"596d5c36bb1df7bac9d12fc0684478acdebd9b91\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-103-36x5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5759, \"got_angle_revision\": \"b5ba693316fdd5e680f2bb4cf1a2f9bed589d8e2\", \"got_dawn_revision\": \"41c24933da1e6c5ec111f824bfc5977be244d3e4\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"596d5c36bb1df7bac9d12fc0684478acdebd9b91\", \"got_revision_cp\": \"refs/heads/master@{#821104}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"af722073314e07138cd3ef04751dc7ce1035219c\", \"got_v8_revision_cp\": \"refs/heads/8.8.178@{#1}\", \"got_webrtc_revision\": \"0fb0eb3e80c09e0483c4dccdaa472e7e81f182fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32491}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"596d5c36bb1df7bac9d12fc0684478acdebd9b91\", \"swarm_hashes_refs/heads/master(at){#821104}_without_patch\": {\"android_browsertests\": \"f58d456d418e4793c0c52d5c0355d0ac7a9d4b24\", \"chrome_public_test_apk\": \"c71ba62934f0af97144b85d9ba2325d7ca266784\", \"content_browsertests\": \"f17aeda24cec87772ac77ce2f9235d3d4e0ee58a\", \"content_shell_test_apk\": \"5062623373f7e1bc3be15a742cb675dc041bd03f\", \"content_unittests\": \"a4194a04ccd4e8b83094476e02c98080ee6dc736\", \"unit_tests\": \"300ed18b9d11ab6dad02b1af788a139af7ca7fbb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_4f0964162888fb5bae249a111360c6881d9714a6e065febf968887beea0fb93f_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-103-36x5\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5613-2fddb4d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** 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_InfoBarOptIn\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603778759602766",
"status": "COMPLETED",
"status_changed_ts": "1603778760396325",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5759",
"builder:android-bfcache-rel",
"buildset:commit/git/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/596d5c36bb1df7bac9d12fc0684478acdebd9b91",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030739867120736400",
"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/8865309804989051456/+/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:4f817b41d5fd0c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603783380901190",
"url": "https://ci.chromium.org/b/8865309804989051456",
"utcnow_ts": "1603783381458418"
}
}
[07:23:01.471] Invocation finished in 1h17m8.683070651s with status FAILED