[12:29:41.250] New invocation is queued and will start shortly
[12:29:42.454] Starting the invocation (attempt 1)
[12:29:42.513] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:29:42.514] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:29:42.514] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033343032074300192",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"54152de9fb05acdd258a5c4b75b933f428e141ac\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9033343032074300192",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/54152de9fb05acdd258a5c4b75b933f428e141ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54152de9fb05acdd258a5c4b75b933f428e141ac",
"gitiles_ref:refs/heads/master"
]
}
[12:29:43.263] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601296182682453",
"id": "8867912969906425360",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54152de9fb05acdd258a5c4b75b933f428e141ac\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601296183150428",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5249",
"builder:android-bfcache-rel",
"buildset:commit/git/54152de9fb05acdd258a5c4b75b933f428e141ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54152de9fb05acdd258a5c4b75b933f428e141ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033343032074300192",
"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/8867912969906425360/+/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": "1601296183150586",
"url": "https://ci.chromium.org/b/8867912969906425360",
"utcnow_ts": "1601296183253573"
}
}
[12:29:43.263] Task URL: https://ci.chromium.org/b/8867912969906425360
[12:29:43.263] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:2:0) after 1m0s
[12:29:46.167] Received PubSub notification, asking Buildbucket for the build status
[12:29:46.250] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:43.275] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:2:0)
[12:30:43.275] Timer tick, asking Buildbucket for the build status
[12:30:43.727] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:43.727] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:4:0) after 1m0s
[12:31:43.721] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:4:0)
[12:31:43.721] Timer tick, asking Buildbucket for the build status
[12:31:43.788] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:43.788] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:5:0) after 1m0s
[12:32:43.827] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:5:0)
[12:32:43.827] Timer tick, asking Buildbucket for the build status
[12:32:44.017] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:44.017] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:6:0) after 1m0s
[12:33:44.058] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:6:0)
[12:33:44.058] Timer tick, asking Buildbucket for the build status
[12:33:44.607] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:44.607] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:7:0) after 1m0s
[12:34:44.704] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:7:0)
[12:34:44.704] Timer tick, asking Buildbucket for the build status
[12:34:44.820] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:44.820] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:8:0) after 1m0s
[12:35:44.878] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:8:0)
[12:35:44.878] Timer tick, asking Buildbucket for the build status
[12:35:44.986] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:44.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:9:0) after 1m0s
[12:36:45.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:9:0)
[12:36:45.021] Timer tick, asking Buildbucket for the build status
[12:36:45.246] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:45.246] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:10:0) after 1m0s
[12:37:45.281] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:10:0)
[12:37:45.281] Timer tick, asking Buildbucket for the build status
[12:37:45.631] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:45.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:11:0) after 1m0s
[12:38:45.655] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:11:0)
[12:38:45.655] Timer tick, asking Buildbucket for the build status
[12:38:45.842] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:12:0) after 1m0s
[12:39:46.003] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:12:0)
[12:39:46.003] Timer tick, asking Buildbucket for the build status
[12:39:46.445] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:46.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:13:0) after 1m0s
[12:40:46.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:13:0)
[12:40:46.610] Timer tick, asking Buildbucket for the build status
[12:40:46.875] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:46.875] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:14:0) after 1m0s
[12:41:46.963] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:14:0)
[12:41:46.963] Timer tick, asking Buildbucket for the build status
[12:41:47.783] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:47.783] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:15:0) after 1m0s
[12:42:47.810] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:15:0)
[12:42:47.810] Timer tick, asking Buildbucket for the build status
[12:42:47.884] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:47.884] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:16:0) after 1m0s
[12:43:47.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:16:0)
[12:43:47.954] Timer tick, asking Buildbucket for the build status
[12:43:48.210] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:48.210] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:17:0) after 1m0s
[12:44:48.261] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:17:0)
[12:44:48.261] Timer tick, asking Buildbucket for the build status
[12:44:48.507] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:48.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:18:0) after 1m0s
[12:45:48.482] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:18:0)
[12:45:48.482] Timer tick, asking Buildbucket for the build status
[12:45:48.572] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:48.572] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:19:0) after 1m0s
[12:46:48.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:19:0)
[12:46:48.597] Timer tick, asking Buildbucket for the build status
[12:46:48.672] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:48.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:20:0) after 1m0s
[12:47:48.737] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:20:0)
[12:47:48.737] Timer tick, asking Buildbucket for the build status
[12:47:49.000] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:49.000] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:21:0) after 1m0s
[12:48:49.073] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:21:0)
[12:48:49.073] Timer tick, asking Buildbucket for the build status
[12:48:49.145] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:49.145] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:22:0) after 1m0s
[12:49:49.164] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:22:0)
[12:49:49.164] Timer tick, asking Buildbucket for the build status
[12:49:49.361] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:49.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:23:0) after 1m0s
[12:50:49.429] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:23:0)
[12:50:49.429] Timer tick, asking Buildbucket for the build status
[12:50:49.697] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:49.697] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:24:0) after 1m0s
[12:51:49.738] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:24:0)
[12:51:49.738] Timer tick, asking Buildbucket for the build status
[12:51:49.808] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:49.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:25:0) after 1m0s
[12:52:49.840] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:25:0)
[12:52:49.840] Timer tick, asking Buildbucket for the build status
[12:52:50.888] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:50.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:26:0) after 1m0s
[12:53:50.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:26:0)
[12:53:50.978] Timer tick, asking Buildbucket for the build status
[12:53:51.061] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:51.061] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:27:0) after 1m0s
[12:54:51.344] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:27:0)
[12:54:51.344] Timer tick, asking Buildbucket for the build status
[12:54:51.541] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:51.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:28:0) after 1m0s
[12:55:51.796] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:28:0)
[12:55:51.796] Timer tick, asking Buildbucket for the build status
[12:55:51.903] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:29:0) after 1m0s
[12:56:51.956] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:29:0)
[12:56:51.956] Timer tick, asking Buildbucket for the build status
[12:56:52.022] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:52.022] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:30:0) after 1m0s
[12:57:52.054] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:30:0)
[12:57:52.054] Timer tick, asking Buildbucket for the build status
[12:57:52.176] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:52.176] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:31:0) after 1m0s
[12:58:52.216] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:31:0)
[12:58:52.216] Timer tick, asking Buildbucket for the build status
[12:58:52.287] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:52.287] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:32:0) after 1m0s
[12:59:55.419] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:32:0)
[12:59:55.419] Timer tick, asking Buildbucket for the build status
[12:59:55.614] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:55.614] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:33:0) after 1m0s
[13:00:55.692] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:33:0)
[13:00:55.692] Timer tick, asking Buildbucket for the build status
[13:00:55.816] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:55.816] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:34:0) after 1m0s
[13:01:55.918] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:34:0)
[13:01:55.918] Timer tick, asking Buildbucket for the build status
[13:01:56.301] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:56.301] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:35:0) after 1m0s
[13:02:56.393] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:35:0)
[13:02:56.393] Timer tick, asking Buildbucket for the build status
[13:02:56.568] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:56.568] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:36:0) after 1m0s
[13:03:56.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:36:0)
[13:03:56.603] Timer tick, asking Buildbucket for the build status
[13:03:56.754] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:56.754] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:37:0) after 1m0s
[13:04:56.812] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:37:0)
[13:04:56.812] Timer tick, asking Buildbucket for the build status
[13:04:56.958] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:56.958] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:38:0) after 1m0s
[13:05:57.004] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:38:0)
[13:05:57.004] Timer tick, asking Buildbucket for the build status
[13:05:57.215] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:57.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:39:0) after 1m0s
[13:06:57.234] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:39:0)
[13:06:57.234] Timer tick, asking Buildbucket for the build status
[13:06:57.304] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:40:0) after 1m0s
[13:07:57.365] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:40:0)
[13:07:57.365] Timer tick, asking Buildbucket for the build status
[13:07:57.517] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:57.517] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:41:0) after 1m0s
[13:08:57.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:41:0)
[13:08:57.603] Timer tick, asking Buildbucket for the build status
[13:08:58.099] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:58.099] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:42:0) after 1m0s
[13:09:58.133] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:42:0)
[13:09:58.133] Timer tick, asking Buildbucket for the build status
[13:09:58.269] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:58.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:43:0) after 1m0s
[13:10:58.334] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:43:0)
[13:10:58.334] Timer tick, asking Buildbucket for the build status
[13:10:58.635] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:58.635] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:44:0) after 1m0s
[13:11:58.702] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:44:0)
[13:11:58.702] Timer tick, asking Buildbucket for the build status
[13:11:58.795] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:58.795] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:45:0) after 1m0s
[13:12:58.837] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:45:0)
[13:12:58.837] Timer tick, asking Buildbucket for the build status
[13:12:59.133] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:59.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:46:0) after 1m0s
[13:13:59.199] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:46:0)
[13:13:59.199] Timer tick, asking Buildbucket for the build status
[13:13:59.290] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:59.290] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:47:0) after 1m0s
[13:14:59.348] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:47:0)
[13:14:59.348] Timer tick, asking Buildbucket for the build status
[13:14:59.611] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:59.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:48:0) after 1m0s
[13:15:59.633] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:48:0)
[13:15:59.633] Timer tick, asking Buildbucket for the build status
[13:15:59.841] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:59.841] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:49:0) after 1m0s
[13:16:59.897] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:49:0)
[13:16:59.897] Timer tick, asking Buildbucket for the build status
[13:16:59.964] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:59.964] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:50:0) after 1m0s
[13:17:59.991] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:50:0)
[13:17:59.991] Timer tick, asking Buildbucket for the build status
[13:18:00.159] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:00.159] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:51:0) after 1m0s
[13:19:00.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:51:0)
[13:19:00.239] Timer tick, asking Buildbucket for the build status
[13:19:00.383] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:00.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:52:0) after 1m0s
[13:20:00.481] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:52:0)
[13:20:00.481] Timer tick, asking Buildbucket for the build status
[13:20:00.636] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:00.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:53:0) after 1m0s
[13:21:00.662] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:53:0)
[13:21:00.662] Timer tick, asking Buildbucket for the build status
[13:21:00.883] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:00.883] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:54:0) after 1m0s
[13:22:00.990] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:54:0)
[13:22:00.990] Timer tick, asking Buildbucket for the build status
[13:22:01.399] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:01.399] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:55:0) after 1m0s
[13:23:02.626] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:55:0)
[13:23:02.626] Timer tick, asking Buildbucket for the build status
[13:23:02.701] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:02.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:56:0) after 1m0s
[13:24:02.818] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:56:0)
[13:24:02.818] Timer tick, asking Buildbucket for the build status
[13:24:03.034] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:03.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:57:0) after 1m0s
[13:25:03.094] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:57:0)
[13:25:03.094] Timer tick, asking Buildbucket for the build status
[13:25:03.372] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:03.372] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:58:0) after 1m0s
[13:26:03.591] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:58:0)
[13:26:03.591] Timer tick, asking Buildbucket for the build status
[13:26:03.677] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:03.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:59:0) after 1m0s
[13:27:03.655] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:59:0)
[13:27:03.655] Timer tick, asking Buildbucket for the build status
[13:27:03.781] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:60:0) after 1m0s
[13:28:03.806] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:60:0)
[13:28:03.806] Timer tick, asking Buildbucket for the build status
[13:28:03.906] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:03.906] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:61:0) after 1m0s
[13:29:03.952] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:61:0)
[13:29:03.952] Timer tick, asking Buildbucket for the build status
[13:29:04.011] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:04.011] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:62:0) after 1m0s
[13:30:04.011] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:62:0)
[13:30:04.011] Timer tick, asking Buildbucket for the build status
[13:30:04.254] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:04.254] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:63:0) after 1m0s
[13:31:04.373] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:63:0)
[13:31:04.373] Timer tick, asking Buildbucket for the build status
[13:31:04.615] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:64:0) after 1m0s
[13:32:04.659] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:64:0)
[13:32:04.659] Timer tick, asking Buildbucket for the build status
[13:32:04.829] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:04.829] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:65:0) after 1m0s
[13:33:04.882] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:65:0)
[13:33:04.882] Timer tick, asking Buildbucket for the build status
[13:33:04.988] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:04.988] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:66:0) after 1m0s
[13:34:05.106] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:66:0)
[13:34:05.106] Timer tick, asking Buildbucket for the build status
[13:34:05.207] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:05.207] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:67:0) after 1m0s
[13:35:05.452] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:67:0)
[13:35:05.452] Timer tick, asking Buildbucket for the build status
[13:35:05.572] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:05.572] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:68:0) after 1m0s
[13:36:05.726] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:68:0)
[13:36:05.726] Timer tick, asking Buildbucket for the build status
[13:36:05.979] Build 8867912969906425360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:05.979] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9033343032074300192:69:0) after 1m0s
[13:36:31.362] Received PubSub notification, asking Buildbucket for the build status
[13:36:31.523] Build 8867912969906425360: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:36:31.523] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601300190120365",
"created_by": "project:chromium",
"created_ts": "1601296182682453",
"failure_reason": "BUILD_FAILURE",
"id": "8867912969906425360",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54152de9fb05acdd258a5c4b75b933f428e141ac\"}}",
"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.contextualsearch.ContextualSearchManagerTest#testLoggedEventId\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testAppMenuSuppressedWhenMaximized__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableLongpress\\n\\n- ...10 more failure(s) (14 total)...\"}, \"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\": \"54152de9fb05acdd258a5c4b75b933f428e141ac\", \"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-50-50pb\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5249, \"got_angle_revision\": \"d2f6cc6f606cdaefc69575a495c3be47085b4c75\", \"got_dawn_revision\": \"ed2b465f860720be30eeba35a56088dc5ba66d46\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"54152de9fb05acdd258a5c4b75b933f428e141ac\", \"got_revision_cp\": \"refs/heads/master@{#811204}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"b94db86cf9b3369ebd3c99ffe0d4072bac0a7b75\", \"got_v8_revision\": \"e79440e729c6f43073a53dfa37e970370481b619\", \"got_v8_revision_cp\": \"refs/heads/8.7.203@{#1}\", \"got_webrtc_revision\": \"111de34102c7ab137a512796356c3683e54f636e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32205}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54152de9fb05acdd258a5c4b75b933f428e141ac\", \"swarm_hashes\": {\"android_browsertests\": \"eeaccbd51c160d1afc8d65c6673c6cc48390e3e5\", \"chrome_public_test_apk\": \"b2f38530d40ae39a605e273420fff7673f3f3df4\", \"content_browsertests\": \"283b864946672b70fbfdb7befb2d77594e65823a\", \"content_shell_test_apk\": \"fdc0bd095952ac647e1927e87f1db0b097d0628d\", \"content_unittests\": \"77cdc189527fb3ced4ef42993846f29aefe800a2\", \"unit_tests\": \"b945d75a350ac5247eaa9daa54bb302698191a3b\"}, \"swarm_hashes_refs/heads/master(at){#811204}_without_patch\": {\"android_browsertests\": \"eeaccbd51c160d1afc8d65c6673c6cc48390e3e5\", \"chrome_public_test_apk\": \"b2f38530d40ae39a605e273420fff7673f3f3df4\", \"content_browsertests\": \"283b864946672b70fbfdb7befb2d77594e65823a\", \"content_shell_test_apk\": \"fdc0bd095952ac647e1927e87f1db0b097d0628d\", \"content_unittests\": \"77cdc189527fb3ced4ef42993846f29aefe800a2\", \"unit_tests\": \"b945d75a350ac5247eaa9daa54bb302698191a3b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_13783b267927983fbbb408e8d075be810d518e5d39840d204fcc7e5c8afb939d_v2\", \"builder_94d0e6b486f4dd3f47aea45f0b8188b5a7dac4a2c051cc02be079383293d8e21_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-50-50pb\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"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\": [\"5541-5d998a0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testLoggedEventId\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testAppMenuSuppressedWhenMaximized__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableTranslations\\n\\n- org.chromium.chrome.browser.contextualsearch.ContextualSearchManagerTest#testExpandBeforeSearchTermResolution__enableLongpress\\n\\n- ...10 more failure(s) (14 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601296184693586",
"status": "COMPLETED",
"status_changed_ts": "1601296185592506",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5249",
"builder:android-bfcache-rel",
"buildset:commit/git/54152de9fb05acdd258a5c4b75b933f428e141ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54152de9fb05acdd258a5c4b75b933f428e141ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033343032074300192",
"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/8867912969906425360/+/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:4eed82399067fe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1601300190532203",
"url": "https://ci.chromium.org/b/8867912969906425360",
"utcnow_ts": "1601300191510175"
}
}
[13:36:31.523] Invocation finished in 1h6m50.291135593s with status FAILED