[16:57:22.145] New invocation is queued and will start shortly
[16:57:23.443] Starting the invocation (attempt 1)
[16:57:23.481] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:57:23.482] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:57:23.482] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037765442357638496",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a518a41e0093cd1de527f671f3c6efb4fc87a046\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9037765442357638496",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"gitiles_ref:refs/heads/master"
]
}
[16:57:24.174] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597078643591080",
"id": "8872335380176151808",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a518a41e0093cd1de527f671f3c6efb4fc87a046\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597078644003831",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4414",
"builder:android-bfcache-rel",
"buildset:commit/git/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037765442357638496",
"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/8872335380176151808/+/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": "1597078644004036",
"url": "https://ci.chromium.org/b/8872335380176151808",
"utcnow_ts": "1597078644165394"
}
}
[16:57:24.174] Task URL: https://ci.chromium.org/b/8872335380176151808
[16:57:24.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:2:0) after 1m0s
[16:57:37.527] Received PubSub notification, asking Buildbucket for the build status
[16:57:37.722] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:24.264] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:2:0)
[16:58:24.265] Timer tick, asking Buildbucket for the build status
[16:58:24.563] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:24.563] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:4:0) after 1m0s
[16:59:24.664] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:4:0)
[16:59:24.664] Timer tick, asking Buildbucket for the build status
[16:59:24.923] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:24.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:5:0) after 1m0s
[17:00:24.927] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:5:0)
[17:00:24.927] Timer tick, asking Buildbucket for the build status
[17:00:24.999] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:24.999] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:6:0) after 1m0s
[17:01:25.195] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:6:0)
[17:01:25.195] Timer tick, asking Buildbucket for the build status
[17:01:25.353] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:25.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:7:0) after 1m0s
[17:02:25.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:7:0)
[17:02:25.603] Timer tick, asking Buildbucket for the build status
[17:02:25.833] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:8:0) after 1m0s
[17:03:25.881] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:8:0)
[17:03:25.881] Timer tick, asking Buildbucket for the build status
[17:03:26.023] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:26.023] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:9:0) after 1m0s
[17:04:26.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:9:0)
[17:04:26.043] Timer tick, asking Buildbucket for the build status
[17:04:26.222] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:26.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:10:0) after 1m0s
[17:05:26.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:10:0)
[17:05:26.303] Timer tick, asking Buildbucket for the build status
[17:05:26.465] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:26.465] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:11:0) after 1m0s
[17:06:26.529] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:11:0)
[17:06:26.529] Timer tick, asking Buildbucket for the build status
[17:06:26.626] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:26.627] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:12:0) after 1m0s
[17:07:26.700] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:12:0)
[17:07:26.700] Timer tick, asking Buildbucket for the build status
[17:07:27.231] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:27.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:13:0) after 1m0s
[17:08:27.611] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:13:0)
[17:08:27.611] Timer tick, asking Buildbucket for the build status
[17:08:27.776] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:27.776] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:14:0) after 1m0s
[17:09:27.864] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:14:0)
[17:09:27.864] Timer tick, asking Buildbucket for the build status
[17:09:27.950] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:27.950] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:15:0) after 1m0s
[17:10:28.164] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:15:0)
[17:10:28.164] Timer tick, asking Buildbucket for the build status
[17:10:28.510] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:28.510] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:16:0) after 1m0s
[17:11:28.765] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:16:0)
[17:11:28.765] Timer tick, asking Buildbucket for the build status
[17:11:28.882] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:28.882] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:17:0) after 1m0s
[17:12:28.970] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:17:0)
[17:12:28.970] Timer tick, asking Buildbucket for the build status
[17:12:29.134] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:29.134] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:18:0) after 1m0s
[17:13:29.245] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:18:0)
[17:13:29.245] Timer tick, asking Buildbucket for the build status
[17:13:29.338] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:29.338] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:19:0) after 1m0s
[17:14:29.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:19:0)
[17:14:29.460] Timer tick, asking Buildbucket for the build status
[17:14:29.808] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:29.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:20:0) after 1m0s
[17:15:29.983] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:20:0)
[17:15:29.983] Timer tick, asking Buildbucket for the build status
[17:15:31.517] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:31.517] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:21:0) after 1m0s
[17:16:31.656] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:21:0)
[17:16:31.656] Timer tick, asking Buildbucket for the build status
[17:16:32.178] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:32.178] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:22:0) after 1m0s
[17:17:32.243] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:22:0)
[17:17:32.243] Timer tick, asking Buildbucket for the build status
[17:17:32.606] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:32.606] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:23:0) after 1m0s
[17:18:32.702] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:23:0)
[17:18:32.702] Timer tick, asking Buildbucket for the build status
[17:18:32.797] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:32.797] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:24:0) after 1m0s
[17:19:32.885] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:24:0)
[17:19:32.885] Timer tick, asking Buildbucket for the build status
[17:19:32.995] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:32.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:25:0) after 1m0s
[17:20:33.094] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:25:0)
[17:20:33.094] Timer tick, asking Buildbucket for the build status
[17:20:33.213] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:33.213] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:26:0) after 1m0s
[17:21:33.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:26:0)
[17:21:33.286] Timer tick, asking Buildbucket for the build status
[17:21:33.432] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:33.432] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:27:0) after 1m0s
[17:22:33.445] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:27:0)
[17:22:33.445] Timer tick, asking Buildbucket for the build status
[17:22:33.833] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:28:0) after 1m0s
[17:23:33.897] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:28:0)
[17:23:33.897] Timer tick, asking Buildbucket for the build status
[17:23:34.036] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:34.036] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:29:0) after 1m0s
[17:24:34.042] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:29:0)
[17:24:34.042] Timer tick, asking Buildbucket for the build status
[17:24:34.132] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:34.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:30:0) after 1m0s
[17:25:34.212] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:30:0)
[17:25:34.212] Timer tick, asking Buildbucket for the build status
[17:25:34.278] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:34.278] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:31:0) after 1m0s
[17:26:34.387] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:31:0)
[17:26:34.387] Timer tick, asking Buildbucket for the build status
[17:26:34.532] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:34.532] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:32:0) after 1m0s
[17:27:34.576] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:32:0)
[17:27:34.576] Timer tick, asking Buildbucket for the build status
[17:27:34.831] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:34.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:33:0) after 1m0s
[17:28:34.984] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:33:0)
[17:28:34.984] Timer tick, asking Buildbucket for the build status
[17:28:35.190] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:35.190] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:34:0) after 1m0s
[17:29:35.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:34:0)
[17:29:35.312] Timer tick, asking Buildbucket for the build status
[17:29:35.535] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:35.535] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:35:0) after 1m0s
[17:30:35.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:35:0)
[17:30:35.609] Timer tick, asking Buildbucket for the build status
[17:30:35.782] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:35.782] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:36:0) after 1m0s
[17:31:35.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:36:0)
[17:31:35.791] Timer tick, asking Buildbucket for the build status
[17:31:36.001] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:36.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:37:0) after 1m0s
[17:32:36.066] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:37:0)
[17:32:36.066] Timer tick, asking Buildbucket for the build status
[17:32:36.480] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:36.480] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:38:0) after 1m0s
[17:33:36.507] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:38:0)
[17:33:36.507] Timer tick, asking Buildbucket for the build status
[17:33:36.616] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:36.616] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:39:0) after 1m0s
[17:34:36.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:39:0)
[17:34:36.706] Timer tick, asking Buildbucket for the build status
[17:34:36.826] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:36.826] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:40:0) after 1m0s
[17:35:36.851] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:40:0)
[17:35:36.851] Timer tick, asking Buildbucket for the build status
[17:35:37.391] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:37.391] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:41:0) after 1m0s
[17:36:37.394] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:41:0)
[17:36:37.394] Timer tick, asking Buildbucket for the build status
[17:36:37.683] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:37.683] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:42:0) after 1m0s
[17:37:37.748] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:42:0)
[17:37:37.748] Timer tick, asking Buildbucket for the build status
[17:37:38.755] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:38.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:43:0) after 1m0s
[17:38:38.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:43:0)
[17:38:38.784] Timer tick, asking Buildbucket for the build status
[17:38:38.874] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:38.874] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:44:0) after 1m0s
[17:39:38.893] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:44:0)
[17:39:38.893] Timer tick, asking Buildbucket for the build status
[17:39:38.991] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:38.991] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:45:0) after 1m0s
[17:40:39.193] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:45:0)
[17:40:39.193] Timer tick, asking Buildbucket for the build status
[17:40:39.276] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:39.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:46:0) after 1m0s
[17:41:39.297] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:46:0)
[17:41:39.298] Timer tick, asking Buildbucket for the build status
[17:41:39.402] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:39.402] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:47:0) after 1m0s
[17:42:39.585] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:47:0)
[17:42:39.585] Timer tick, asking Buildbucket for the build status
[17:42:39.784] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:39.784] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:48:0) after 1m0s
[17:43:39.820] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:48:0)
[17:43:39.820] Timer tick, asking Buildbucket for the build status
[17:43:39.917] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:39.917] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:49:0) after 1m0s
[17:44:40.421] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:49:0)
[17:44:40.421] Timer tick, asking Buildbucket for the build status
[17:44:40.753] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:40.753] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:50:0) after 1m0s
[17:45:40.975] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:50:0)
[17:45:40.975] Timer tick, asking Buildbucket for the build status
[17:45:41.092] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:41.092] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:51:0) after 1m0s
[17:46:41.247] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:51:0)
[17:46:41.247] Timer tick, asking Buildbucket for the build status
[17:46:41.376] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:41.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:52:0) after 1m0s
[17:47:41.501] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:52:0)
[17:47:41.501] Timer tick, asking Buildbucket for the build status
[17:47:41.658] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:41.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:53:0) after 1m0s
[17:48:41.775] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:53:0)
[17:48:41.775] Timer tick, asking Buildbucket for the build status
[17:48:41.860] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:41.860] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:54:0) after 1m0s
[17:49:41.999] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:54:0)
[17:49:41.999] Timer tick, asking Buildbucket for the build status
[17:49:42.181] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:42.181] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:55:0) after 1m0s
[17:50:42.339] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:55:0)
[17:50:42.339] Timer tick, asking Buildbucket for the build status
[17:50:42.669] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:42.669] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:56:0) after 1m0s
[17:51:42.748] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:56:0)
[17:51:42.748] Timer tick, asking Buildbucket for the build status
[17:51:42.880] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:42.880] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:57:0) after 1m0s
[17:52:42.916] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:57:0)
[17:52:42.916] Timer tick, asking Buildbucket for the build status
[17:52:43.217] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:43.217] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:58:0) after 1m0s
[17:53:43.238] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:58:0)
[17:53:43.238] Timer tick, asking Buildbucket for the build status
[17:53:43.661] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:43.661] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:59:0) after 1m0s
[17:54:43.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:59:0)
[17:54:43.716] Timer tick, asking Buildbucket for the build status
[17:54:43.989] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:43.989] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:60:0) after 1m0s
[17:55:44.037] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:60:0)
[17:55:44.037] Timer tick, asking Buildbucket for the build status
[17:55:44.237] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:44.238] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:61:0) after 1m0s
[17:56:44.258] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:61:0)
[17:56:44.258] Timer tick, asking Buildbucket for the build status
[17:56:44.413] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:44.413] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:62:0) after 1m0s
[17:57:44.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:62:0)
[17:57:44.465] Timer tick, asking Buildbucket for the build status
[17:57:44.585] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:44.585] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:63:0) after 1m0s
[17:58:44.608] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:63:0)
[17:58:44.608] Timer tick, asking Buildbucket for the build status
[17:58:44.923] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:44.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:64:0) after 1m0s
[17:59:44.972] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:64:0)
[17:59:44.972] Timer tick, asking Buildbucket for the build status
[17:59:45.054] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:45.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:65:0) after 1m0s
[18:00:45.144] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:65:0)
[18:00:45.144] Timer tick, asking Buildbucket for the build status
[18:00:45.349] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:45.349] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:66:0) after 1m0s
[18:01:45.386] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:66:0)
[18:01:45.386] Timer tick, asking Buildbucket for the build status
[18:01:45.500] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:45.500] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:67:0) after 1m0s
[18:02:45.537] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:67:0)
[18:02:45.537] Timer tick, asking Buildbucket for the build status
[18:02:45.953] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:45.953] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:68:0) after 1m0s
[18:03:46.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:68:0)
[18:03:46.036] Timer tick, asking Buildbucket for the build status
[18:03:47.421] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:47.421] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:69:0) after 1m0s
[18:04:47.466] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:69:0)
[18:04:47.466] Timer tick, asking Buildbucket for the build status
[18:04:47.701] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:47.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:70:0) after 1m0s
[18:05:47.691] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:70:0)
[18:05:47.691] Timer tick, asking Buildbucket for the build status
[18:05:47.958] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:47.959] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:71:0) after 1m0s
[18:06:47.982] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:71:0)
[18:06:47.982] Timer tick, asking Buildbucket for the build status
[18:06:48.370] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.370] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:72:0) after 1m0s
[18:07:48.374] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:72:0)
[18:07:48.374] Timer tick, asking Buildbucket for the build status
[18:07:48.726] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.726] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:73:0) after 1m0s
[18:08:48.806] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:73:0)
[18:08:48.806] Timer tick, asking Buildbucket for the build status
[18:08:49.192] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:49.192] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:74:0) after 1m0s
[18:09:49.289] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:74:0)
[18:09:49.289] Timer tick, asking Buildbucket for the build status
[18:09:49.384] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:49.384] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:75:0) after 1m0s
[18:10:49.449] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:75:0)
[18:10:49.449] Timer tick, asking Buildbucket for the build status
[18:10:49.545] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:49.545] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:76:0) after 1m0s
[18:11:49.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:76:0)
[18:11:49.567] Timer tick, asking Buildbucket for the build status
[18:11:49.676] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:49.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:77:0) after 1m0s
[18:12:49.779] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:77:0)
[18:12:49.779] Timer tick, asking Buildbucket for the build status
[18:12:49.941] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:49.941] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:78:0) after 1m0s
[18:13:49.955] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:78:0)
[18:13:49.955] Timer tick, asking Buildbucket for the build status
[18:13:50.142] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:50.142] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:79:0) after 1m0s
[18:14:50.176] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:79:0)
[18:14:50.176] Timer tick, asking Buildbucket for the build status
[18:14:50.658] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:80:0) after 1m0s
[18:15:50.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:80:0)
[18:15:50.749] Timer tick, asking Buildbucket for the build status
[18:15:50.861] Build 8872335380176151808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:50.861] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037765442357638496:81:0) after 1m0s
[18:15:54.277] Received PubSub notification, asking Buildbucket for the build status
[18:15:54.407] Build 8872335380176151808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:15:54.407] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597083353186887",
"created_by": "project:chromium",
"created_ts": "1597078643591080",
"id": "8872335380176151808",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a518a41e0093cd1de527f671f3c6efb4fc87a046\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-103-zr24\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4414, \"got_angle_revision\": \"d5099cc1545ff4af95dce68f1bec4fc05ce54a01\", \"got_dawn_revision\": \"1662e97f324ef120cf097ba38d63a69cad83593c\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"a518a41e0093cd1de527f671f3c6efb4fc87a046\", \"got_revision_cp\": \"refs/heads/master@{#796420}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"59b4828f293eb16f8983ae9ea0a8a0a84cf8174c\", \"got_v8_revision\": \"4f52dde736e657ac01cf74a6cdc38c752f9a5dae\", \"got_v8_revision_cp\": \"refs/heads/8.6.337@{#1}\", \"got_webrtc_revision\": \"582102c9b7f48f6ccad956fe17241668a90cb3fc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31892}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a518a41e0093cd1de527f671f3c6efb4fc87a046\", \"swarm_hashes\": {\"android_browsertests\": \"81f0bde89dc9907277190d5faeda99e4b93318be\", \"chrome_public_test_apk\": \"49fa30217205226cce5518dff09859bfa0c3fd59\", \"content_browsertests\": \"6dd3edeac26060b8b312108dfe06791d0926457a\", \"content_shell_test_apk\": \"a8c2c64b89c8e743e6495297d3d0e7f41bdae3f0\", \"content_unittests\": \"6daa8a158eee5137e98b57239ed6349b919957d7\", \"unit_tests\": \"c9ed56a9fce110addd5503fb8d0578b77c9db513\"}, \"swarm_hashes_refs/heads/master(at){#796420}_without_patch\": {\"android_browsertests\": \"81f0bde89dc9907277190d5faeda99e4b93318be\", \"chrome_public_test_apk\": \"49fa30217205226cce5518dff09859bfa0c3fd59\", \"content_browsertests\": \"6dd3edeac26060b8b312108dfe06791d0926457a\", \"content_shell_test_apk\": \"a8c2c64b89c8e743e6495297d3d0e7f41bdae3f0\", \"content_unittests\": \"6daa8a158eee5137e98b57239ed6349b919957d7\", \"unit_tests\": \"c9ed56a9fce110addd5503fb8d0578b77c9db513\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1415e99d1ada386cfb0c84043d2495490d2cdcd28ad7785eb6837a9119e6428f_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_eb75bb97ad9a82e6e51bc242be3605b13562b7c1ffaba99d94159b9b70e03009_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-zr24\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5472-d0af7f3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1597078655279752",
"status": "COMPLETED",
"status_changed_ts": "1597083353592052",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4414",
"builder:android-bfcache-rel",
"buildset:commit/git/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a518a41e0093cd1de527f671f3c6efb4fc87a046",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037765442357638496",
"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/8872335380176151808/+/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:4df21faf034da610",
"user_agent:luci-scheduler"
],
"updated_ts": "1597083353597961",
"url": "https://ci.chromium.org/b/8872335380176151808",
"utcnow_ts": "1597083354394920"
}
}
[18:15:54.407] Invocation finished in 1h18m32.279848478s with status SUCCEEDED