[18:34:14.328] New invocation is queued and will start shortly
[18:34:15.444] Starting the invocation (attempt 1)
[18:34:15.477] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:34:15.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:34:15.477] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023263833216779008",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9023263833216779008",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"gitiles_ref:refs/heads/master"
]
}
[18:34:16.187] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610908455566770",
"id": "8857833771254942400",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610908455956674",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7309",
"builder:android-bfcache-rel",
"buildset:commit/git/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023263833216779008",
"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/8857833771254942400/+/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": "1610908455956786",
"url": "https://ci.chromium.org/b/8857833771254942400",
"utcnow_ts": "1610908456178487"
}
}
[18:34:16.187] Task URL: https://ci.chromium.org/b/8857833771254942400
[18:34:16.187] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:2:0) after 1m0s
[18:35:16.347] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:2:0)
[18:35:16.347] Timer tick, asking Buildbucket for the build status
[18:35:16.694] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:16.694] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:3:0) after 1m0s
[18:36:16.798] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:3:0)
[18:36:16.798] Timer tick, asking Buildbucket for the build status
[18:36:16.873] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:16.873] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:4:0) after 1m0s
[18:37:16.973] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:4:0)
[18:37:16.973] Timer tick, asking Buildbucket for the build status
[18:37:17.247] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:17.247] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:5:0) after 1m0s
[18:38:17.435] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:5:0)
[18:38:17.435] Timer tick, asking Buildbucket for the build status
[18:38:17.566] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:17.566] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:6:0) after 1m0s
[18:39:17.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:6:0)
[18:39:17.685] Timer tick, asking Buildbucket for the build status
[18:39:18.066] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:18.066] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:7:0) after 1m0s
[18:40:18.112] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:7:0)
[18:40:18.112] Timer tick, asking Buildbucket for the build status
[18:40:18.288] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:18.288] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:8:0) after 1m0s
[18:41:18.364] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:8:0)
[18:41:18.364] Timer tick, asking Buildbucket for the build status
[18:41:18.445] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:18.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:9:0) after 1m0s
[18:42:18.526] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:9:0)
[18:42:18.526] Timer tick, asking Buildbucket for the build status
[18:42:18.584] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:18.584] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:10:0) after 1m0s
[18:43:18.604] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:10:0)
[18:43:18.605] Timer tick, asking Buildbucket for the build status
[18:43:18.718] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:18.718] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:11:0) after 1m0s
[18:44:18.796] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:11:0)
[18:44:18.796] Timer tick, asking Buildbucket for the build status
[18:44:18.905] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:18.905] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:12:0) after 1m0s
[18:45:18.929] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:12:0)
[18:45:18.929] Timer tick, asking Buildbucket for the build status
[18:45:19.614] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:19.614] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:13:0) after 1m0s
[18:46:19.632] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:13:0)
[18:46:19.632] Timer tick, asking Buildbucket for the build status
[18:46:19.700] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:19.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:14:0) after 1m0s
[18:47:19.724] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:14:0)
[18:47:19.724] Timer tick, asking Buildbucket for the build status
[18:47:19.803] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:19.803] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:15:0) after 1m0s
[18:48:19.824] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:15:0)
[18:48:19.824] Timer tick, asking Buildbucket for the build status
[18:48:19.920] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:19.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:16:0) after 1m0s
[18:49:19.983] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:16:0)
[18:49:19.983] Timer tick, asking Buildbucket for the build status
[18:49:20.391] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:17:0) after 1m0s
[18:50:20.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:17:0)
[18:50:20.385] Timer tick, asking Buildbucket for the build status
[18:50:20.525] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:20.525] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:18:0) after 1m0s
[18:51:20.544] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:18:0)
[18:51:20.544] Timer tick, asking Buildbucket for the build status
[18:51:20.663] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:20.663] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:19:0) after 1m0s
[18:52:20.728] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:19:0)
[18:52:20.728] Timer tick, asking Buildbucket for the build status
[18:52:20.898] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:20.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:20:0) after 1m0s
[18:53:20.918] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:20:0)
[18:53:20.918] Timer tick, asking Buildbucket for the build status
[18:53:21.005] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:21.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:21:0) after 1m0s
[18:54:21.025] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:21:0)
[18:54:21.025] Timer tick, asking Buildbucket for the build status
[18:54:21.528] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:21.528] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:22:0) after 1m0s
[18:55:21.550] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:22:0)
[18:55:21.550] Timer tick, asking Buildbucket for the build status
[18:55:21.680] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:21.680] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:23:0) after 1m0s
[18:56:21.711] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:23:0)
[18:56:21.711] Timer tick, asking Buildbucket for the build status
[18:56:21.833] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:21.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:24:0) after 1m0s
[18:57:21.854] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:24:0)
[18:57:21.854] Timer tick, asking Buildbucket for the build status
[18:57:22.005] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:22.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:25:0) after 1m0s
[18:58:22.004] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:25:0)
[18:58:22.004] Timer tick, asking Buildbucket for the build status
[18:58:22.088] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:22.088] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:26:0) after 1m0s
[18:59:22.111] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:26:0)
[18:59:22.111] Timer tick, asking Buildbucket for the build status
[18:59:22.222] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:22.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:27:0) after 1m0s
[19:00:22.243] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:27:0)
[19:00:22.243] Timer tick, asking Buildbucket for the build status
[19:00:22.336] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:22.336] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:28:0) after 1m0s
[19:01:22.366] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:28:0)
[19:01:22.366] Timer tick, asking Buildbucket for the build status
[19:01:22.624] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:22.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:29:0) after 1m0s
[19:02:22.606] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:29:0)
[19:02:22.606] Timer tick, asking Buildbucket for the build status
[19:02:22.798] Build 8857833771254942400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:22.798] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:30:0) after 1m0s
[19:02:46.786] Received PubSub notification, asking Buildbucket for the build status
[19:02:46.899] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:22.867] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:30:0)
[19:03:22.867] Timer tick, asking Buildbucket for the build status
[19:03:23.044] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:23.044] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:32:0) after 1m0s
[19:04:23.096] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:32:0)
[19:04:23.096] Timer tick, asking Buildbucket for the build status
[19:04:23.192] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:23.192] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:33:0) after 1m0s
[19:05:23.247] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:33:0)
[19:05:23.247] Timer tick, asking Buildbucket for the build status
[19:05:23.352] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:23.352] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:34:0) after 1m0s
[19:06:23.425] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:34:0)
[19:06:23.425] Timer tick, asking Buildbucket for the build status
[19:06:23.567] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:23.567] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:35:0) after 1m0s
[19:07:23.604] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:35:0)
[19:07:23.604] Timer tick, asking Buildbucket for the build status
[19:07:23.665] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:23.665] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:36:0) after 1m0s
[19:08:23.689] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:36:0)
[19:08:23.689] Timer tick, asking Buildbucket for the build status
[19:08:23.747] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:23.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:37:0) after 1m0s
[19:09:23.776] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:37:0)
[19:09:23.776] Timer tick, asking Buildbucket for the build status
[19:09:24.029] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:24.030] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:38:0) after 1m0s
[19:10:24.071] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:38:0)
[19:10:24.071] Timer tick, asking Buildbucket for the build status
[19:10:24.158] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:24.158] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:39:0) after 1m0s
[19:11:24.194] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:39:0)
[19:11:24.194] Timer tick, asking Buildbucket for the build status
[19:11:24.392] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:40:0) after 1m0s
[19:12:24.414] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:40:0)
[19:12:24.414] Timer tick, asking Buildbucket for the build status
[19:12:24.477] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:24.477] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:41:0) after 1m0s
[19:13:24.524] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:41:0)
[19:13:24.524] Timer tick, asking Buildbucket for the build status
[19:13:24.622] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:24.622] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:42:0) after 1m0s
[19:14:24.654] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:42:0)
[19:14:24.654] Timer tick, asking Buildbucket for the build status
[19:14:24.759] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:24.759] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:43:0) after 1m0s
[19:15:24.804] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:43:0)
[19:15:24.804] Timer tick, asking Buildbucket for the build status
[19:15:24.920] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:24.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:44:0) after 1m0s
[19:16:24.961] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:44:0)
[19:16:24.961] Timer tick, asking Buildbucket for the build status
[19:16:25.377] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:25.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:45:0) after 1m0s
[19:17:25.434] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:45:0)
[19:17:25.434] Timer tick, asking Buildbucket for the build status
[19:17:25.649] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:25.649] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:46:0) after 1m0s
[19:18:25.710] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:46:0)
[19:18:25.710] Timer tick, asking Buildbucket for the build status
[19:18:26.110] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:26.110] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:47:0) after 1m0s
[19:19:26.168] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:47:0)
[19:19:26.168] Timer tick, asking Buildbucket for the build status
[19:19:26.279] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:26.279] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:48:0) after 1m0s
[19:20:26.302] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:48:0)
[19:20:26.302] Timer tick, asking Buildbucket for the build status
[19:20:26.507] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:26.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:49:0) after 1m0s
[19:21:26.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:49:0)
[19:21:26.528] Timer tick, asking Buildbucket for the build status
[19:21:26.588] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:26.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:50:0) after 1m0s
[19:22:26.648] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:50:0)
[19:22:26.648] Timer tick, asking Buildbucket for the build status
[19:22:26.770] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:26.770] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:51:0) after 1m0s
[19:23:26.842] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:51:0)
[19:23:26.842] Timer tick, asking Buildbucket for the build status
[19:23:27.038] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:27.038] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:52:0) after 1m0s
[19:24:27.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:52:0)
[19:24:27.059] Timer tick, asking Buildbucket for the build status
[19:24:27.412] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:27.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:53:0) after 1m0s
[19:25:27.435] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:53:0)
[19:25:27.435] Timer tick, asking Buildbucket for the build status
[19:25:27.499] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:27.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:54:0) after 1m0s
[19:26:27.521] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:54:0)
[19:26:27.521] Timer tick, asking Buildbucket for the build status
[19:26:27.597] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:27.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:55:0) after 1m0s
[19:27:27.617] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:55:0)
[19:27:27.617] Timer tick, asking Buildbucket for the build status
[19:27:27.725] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:27.725] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:56:0) after 1m0s
[19:28:27.719] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:56:0)
[19:28:27.719] Timer tick, asking Buildbucket for the build status
[19:28:27.782] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:27.782] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:57:0) after 1m0s
[19:29:27.868] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:57:0)
[19:29:27.868] Timer tick, asking Buildbucket for the build status
[19:29:27.986] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:27.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:58:0) after 1m0s
[19:30:28.026] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:58:0)
[19:30:28.026] Timer tick, asking Buildbucket for the build status
[19:30:28.611] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:28.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:59:0) after 1m0s
[19:31:28.679] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:59:0)
[19:31:28.679] Timer tick, asking Buildbucket for the build status
[19:31:29.023] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:29.023] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:60:0) after 1m0s
[19:32:29.046] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:60:0)
[19:32:29.046] Timer tick, asking Buildbucket for the build status
[19:32:29.137] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:29.137] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:61:0) after 1m0s
[19:33:29.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:61:0)
[19:33:29.191] Timer tick, asking Buildbucket for the build status
[19:33:29.296] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:29.296] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:62:0) after 1m0s
[19:34:29.319] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:62:0)
[19:34:29.319] Timer tick, asking Buildbucket for the build status
[19:34:29.697] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:29.697] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:63:0) after 1m0s
[19:35:29.719] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:63:0)
[19:35:29.719] Timer tick, asking Buildbucket for the build status
[19:35:29.784] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:29.784] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:64:0) after 1m0s
[19:36:29.805] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:64:0)
[19:36:29.805] Timer tick, asking Buildbucket for the build status
[19:36:29.866] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:29.866] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:65:0) after 1m0s
[19:37:29.905] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:65:0)
[19:37:29.905] Timer tick, asking Buildbucket for the build status
[19:37:29.977] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:29.977] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:66:0) after 1m0s
[19:38:29.984] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:66:0)
[19:38:29.984] Timer tick, asking Buildbucket for the build status
[19:38:30.064] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:30.064] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:67:0) after 1m0s
[19:39:30.099] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:67:0)
[19:39:30.099] Timer tick, asking Buildbucket for the build status
[19:39:30.242] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:30.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:68:0) after 1m0s
[19:40:30.262] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:68:0)
[19:40:30.262] Timer tick, asking Buildbucket for the build status
[19:40:30.450] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:30.450] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:69:0) after 1m0s
[19:41:30.471] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:69:0)
[19:41:30.471] Timer tick, asking Buildbucket for the build status
[19:41:30.547] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:30.547] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:70:0) after 1m0s
[19:42:30.537] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:70:0)
[19:42:30.537] Timer tick, asking Buildbucket for the build status
[19:42:30.623] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:30.623] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:71:0) after 1m0s
[19:43:30.677] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:71:0)
[19:43:30.677] Timer tick, asking Buildbucket for the build status
[19:43:30.997] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:30.997] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:72:0) after 1m0s
[19:44:31.019] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:72:0)
[19:44:31.019] Timer tick, asking Buildbucket for the build status
[19:44:31.373] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:31.373] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:73:0) after 1m0s
[19:45:31.397] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:73:0)
[19:45:31.398] Timer tick, asking Buildbucket for the build status
[19:45:31.470] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:31.470] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:74:0) after 1m0s
[19:46:31.525] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:74:0)
[19:46:31.525] Timer tick, asking Buildbucket for the build status
[19:46:31.588] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:31.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:75:0) after 1m0s
[19:47:31.643] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:75:0)
[19:47:31.643] Timer tick, asking Buildbucket for the build status
[19:47:31.702] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:31.702] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:76:0) after 1m0s
[19:48:31.693] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:76:0)
[19:48:31.693] Timer tick, asking Buildbucket for the build status
[19:48:31.765] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:31.765] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:77:0) after 1m0s
[19:49:31.839] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:77:0)
[19:49:31.839] Timer tick, asking Buildbucket for the build status
[19:49:31.957] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:31.957] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:78:0) after 1m0s
[19:50:32.044] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:78:0)
[19:50:32.044] Timer tick, asking Buildbucket for the build status
[19:50:32.213] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:32.213] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:79:0) after 1m0s
[19:51:32.317] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:79:0)
[19:51:32.317] Timer tick, asking Buildbucket for the build status
[19:51:32.379] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:32.379] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:80:0) after 1m0s
[19:52:32.371] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:80:0)
[19:52:32.371] Timer tick, asking Buildbucket for the build status
[19:52:32.535] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:32.535] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:81:0) after 1m0s
[19:53:32.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:81:0)
[19:53:32.610] Timer tick, asking Buildbucket for the build status
[19:53:32.723] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:32.723] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:82:0) after 1m0s
[19:54:32.751] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:82:0)
[19:54:32.751] Timer tick, asking Buildbucket for the build status
[19:54:32.916] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:32.916] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:83:0) after 1m0s
[19:55:32.931] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:83:0)
[19:55:32.931] Timer tick, asking Buildbucket for the build status
[19:55:32.988] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:32.988] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:84:0) after 1m0s
[19:56:33.338] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:84:0)
[19:56:33.338] Timer tick, asking Buildbucket for the build status
[19:56:33.495] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:33.495] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:85:0) after 1m0s
[19:57:33.585] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:85:0)
[19:57:33.585] Timer tick, asking Buildbucket for the build status
[19:57:33.643] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:33.643] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:86:0) after 1m0s
[19:58:33.698] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:86:0)
[19:58:33.698] Timer tick, asking Buildbucket for the build status
[19:58:33.756] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:33.756] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:87:0) after 1m0s
[19:59:33.765] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:87:0)
[19:59:33.765] Timer tick, asking Buildbucket for the build status
[19:59:33.861] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:33.861] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:88:0) after 1m0s
[20:00:33.887] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:88:0)
[20:00:33.887] Timer tick, asking Buildbucket for the build status
[20:00:34.320] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:34.320] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:89:0) after 1m0s
[20:01:34.388] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:89:0)
[20:01:34.388] Timer tick, asking Buildbucket for the build status
[20:01:34.465] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:34.465] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:90:0) after 1m0s
[20:02:34.492] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:90:0)
[20:02:34.492] Timer tick, asking Buildbucket for the build status
[20:02:34.552] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:34.552] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:91:0) after 1m0s
[20:03:34.593] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:91:0)
[20:03:34.593] Timer tick, asking Buildbucket for the build status
[20:03:34.675] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:34.675] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:92:0) after 1m0s
[20:04:34.739] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:92:0)
[20:04:34.739] Timer tick, asking Buildbucket for the build status
[20:04:34.864] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:34.864] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:93:0) after 1m0s
[20:05:34.897] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:93:0)
[20:05:34.897] Timer tick, asking Buildbucket for the build status
[20:05:35.293] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:35.293] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:94:0) after 1m0s
[20:06:35.357] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:94:0)
[20:06:35.357] Timer tick, asking Buildbucket for the build status
[20:06:35.436] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:35.436] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:95:0) after 1m0s
[20:07:35.514] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:95:0)
[20:07:35.514] Timer tick, asking Buildbucket for the build status
[20:07:35.579] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:35.579] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:96:0) after 1m0s
[20:08:35.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:96:0)
[20:08:35.681] Timer tick, asking Buildbucket for the build status
[20:08:36.122] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:36.122] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:97:0) after 1m0s
[20:09:36.149] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:97:0)
[20:09:36.149] Timer tick, asking Buildbucket for the build status
[20:09:36.263] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:36.263] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:98:0) after 1m0s
[20:10:36.321] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:98:0)
[20:10:36.321] Timer tick, asking Buildbucket for the build status
[20:10:36.700] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:36.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:99:0) after 1m0s
[20:11:36.759] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:99:0)
[20:11:36.759] Timer tick, asking Buildbucket for the build status
[20:11:37.922] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:37.922] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:100:0) after 1m0s
[20:12:37.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:100:0)
[20:12:37.946] Timer tick, asking Buildbucket for the build status
[20:12:38.010] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:38.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:101:0) after 1m0s
[20:13:38.107] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:101:0)
[20:13:38.107] Timer tick, asking Buildbucket for the build status
[20:13:38.206] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:38.206] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:102:0) after 1m0s
[20:14:38.315] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:102:0)
[20:14:38.315] Timer tick, asking Buildbucket for the build status
[20:14:38.898] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:38.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:103:0) after 1m0s
[20:15:39.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:103:0)
[20:15:39.021] Timer tick, asking Buildbucket for the build status
[20:15:39.147] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:39.147] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:104:0) after 1m0s
[20:16:39.228] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:104:0)
[20:16:39.228] Timer tick, asking Buildbucket for the build status
[20:16:39.787] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:39.787] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:105:0) after 1m0s
[20:17:39.887] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:105:0)
[20:17:39.887] Timer tick, asking Buildbucket for the build status
[20:17:40.245] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:40.245] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:106:0) after 1m0s
[20:18:40.241] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:106:0)
[20:18:40.241] Timer tick, asking Buildbucket for the build status
[20:18:40.366] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:40.366] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:107:0) after 1m0s
[20:19:40.387] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:107:0)
[20:19:40.387] Timer tick, asking Buildbucket for the build status
[20:19:40.836] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:108:0) after 1m0s
[20:20:40.894] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:108:0)
[20:20:40.894] Timer tick, asking Buildbucket for the build status
[20:20:41.016] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:41.016] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:109:0) after 1m0s
[20:21:41.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:109:0)
[20:21:41.039] Timer tick, asking Buildbucket for the build status
[20:21:41.177] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:41.177] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:110:0) after 1m0s
[20:22:41.204] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:110:0)
[20:22:41.204] Timer tick, asking Buildbucket for the build status
[20:22:41.317] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:111:0) after 1m0s
[20:23:41.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:111:0)
[20:23:41.385] Timer tick, asking Buildbucket for the build status
[20:23:41.611] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:41.611] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:112:0) after 1m0s
[20:24:41.691] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:112:0)
[20:24:41.691] Timer tick, asking Buildbucket for the build status
[20:24:41.783] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:41.783] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:113:0) after 1m0s
[20:25:41.810] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:113:0)
[20:25:41.810] Timer tick, asking Buildbucket for the build status
[20:25:41.865] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:41.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:114:0) after 1m0s
[20:26:41.913] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:114:0)
[20:26:41.913] Timer tick, asking Buildbucket for the build status
[20:26:42.104] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:42.104] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:115:0) after 1m0s
[20:27:42.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:115:0)
[20:27:42.306] Timer tick, asking Buildbucket for the build status
[20:27:42.402] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:42.402] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:116:0) after 1m0s
[20:28:42.683] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:116:0)
[20:28:42.683] Timer tick, asking Buildbucket for the build status
[20:28:42.818] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:42.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:117:0) after 1m0s
[20:29:43.038] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:117:0)
[20:29:43.038] Timer tick, asking Buildbucket for the build status
[20:29:43.199] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:43.199] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:118:0) after 1m0s
[20:30:43.327] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:118:0)
[20:30:43.327] Timer tick, asking Buildbucket for the build status
[20:30:43.382] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:43.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:119:0) after 1m0s
[20:31:43.469] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:119:0)
[20:31:43.469] Timer tick, asking Buildbucket for the build status
[20:31:43.902] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:43.902] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:120:0) after 1m0s
[20:32:44.072] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:120:0)
[20:32:44.072] Timer tick, asking Buildbucket for the build status
[20:32:44.138] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:44.138] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:121:0) after 1m0s
[20:33:44.184] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:121:0)
[20:33:44.184] Timer tick, asking Buildbucket for the build status
[20:33:44.406] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:44.406] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:122:0) after 1m0s
[20:34:44.438] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:122:0)
[20:34:44.438] Timer tick, asking Buildbucket for the build status
[20:34:44.639] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:44.639] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:123:0) after 1m0s
[20:35:44.760] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:123:0)
[20:35:44.760] Timer tick, asking Buildbucket for the build status
[20:35:44.879] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:44.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:124:0) after 1m0s
[20:36:44.942] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:124:0)
[20:36:44.942] Timer tick, asking Buildbucket for the build status
[20:36:45.004] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:45.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:125:0) after 1m0s
[20:37:45.183] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:125:0)
[20:37:45.183] Timer tick, asking Buildbucket for the build status
[20:37:45.407] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:45.407] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:126:0) after 1m0s
[20:38:45.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:126:0)
[20:38:45.479] Timer tick, asking Buildbucket for the build status
[20:38:45.795] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:45.795] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:127:0) after 1m0s
[20:39:45.867] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:127:0)
[20:39:45.867] Timer tick, asking Buildbucket for the build status
[20:39:46.496] Build 8857833771254942400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:46.496] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9023263833216779008:128:0) after 1m0s
[20:40:31.184] Received PubSub notification, asking Buildbucket for the build status
[20:40:31.349] Build 8857833771254942400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:40:31.349] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610916030491461",
"created_by": "project:chromium",
"created_ts": "1610908455566770",
"id": "8857833771254942400",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\", \"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-57-stdf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7309, \"got_angle_revision\": \"9f09435bc1921d4df8423211216570b6a4c29840\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\", \"got_revision_cp\": \"refs/heads/master@{#844398}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"09729d2ecf49d256f6648494b59076b01786dc51\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33013}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b\", \"swarm_hashes_refs/heads/master(at){#844398}_without_patch\": {\"android_browsertests\": \"2be0c19583a89cc2c40376eece76f07fff6e9e5e\", \"chrome_public_test_apk\": \"c2ee97a1b4b855d4a99d6441eafec2b5bfafdc6e\", \"content_browsertests\": \"a5a94fd0a766dd97f7e4bb0bfba6b7434d89e21c\", \"content_shell_test_apk\": \"fc672ef726ec00b3b1d17039a27e3154db27bc90\", \"content_unittests\": \"6e11a25404b8374d03aa3d18af8009d4089b5a82\", \"unit_tests\": \"49f00474d5506e2107aa43acc076b360cdb8f373\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_5031bc3d586fdedb2b02f62dae64b9ebfd5c405012b7a28b41092bb1d66cfccb_v2\", \"builder_8afdcfef4ae6fecf44bdc59bc36ab1bd30dfc75b00b262fd5e6b3421af127659_v2\", \"builder_be71ec2e45ff41f29ecdc3611cfb7c276cc0ff3138424d653aa2b7cfb51218d3_v2\", \"builder_cb8f28a5d59bb80cbafed89b56af03ba2ea85f5e24941631d330b6bd1d886cb5_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_ea4e7feff896b82ff6619a3b9c5371d5e74ebdd508befd295e2f5ab02f5eefaf_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_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-57-stdf\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610910165487841",
"status": "COMPLETED",
"status_changed_ts": "1610916030918523",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7309",
"builder:android-bfcache-rel",
"buildset:commit/git/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcaf0291f5940a8f03973ca857b2e7c6b4b2ba1b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023263833216779008",
"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/8857833771254942400/+/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:512a71ddb718aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610916030923766",
"url": "https://ci.chromium.org/b/8857833771254942400",
"utcnow_ts": "1610916031298093"
}
}
[20:40:31.349] Invocation finished in 2h6m17.037833966s with status SUCCEEDED