[20:03:48.088] New invocation is queued and will start shortly
[20:03:49.197] Starting the invocation (attempt 1)
[20:03:49.227] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:03:49.227] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:03:49.227] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020540289428595952",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"71310e5a752f4291ad953d4b955e7c40c6905259\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9020540289428595952",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/71310e5a752f4291ad953d4b955e7c40c6905259",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71310e5a752f4291ad953d4b955e7c40c6905259",
"gitiles_ref:refs/heads/master"
]
}
[20:03:50.465] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613505829297437",
"id": "8855110227497822928",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"71310e5a752f4291ad953d4b955e7c40c6905259\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613505830170022",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7850",
"builder:android-bfcache-rel",
"buildset:commit/git/71310e5a752f4291ad953d4b955e7c40c6905259",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71310e5a752f4291ad953d4b955e7c40c6905259",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020540289428595952",
"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/8855110227497822928/+/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": "1613505830170192",
"url": "https://ci.chromium.org/b/8855110227497822928",
"utcnow_ts": "1613505830433704"
}
}
[20:03:50.466] Task URL: https://ci.chromium.org/b/8855110227497822928
[20:03:50.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:2:0) after 1m0s
[20:03:53.448] Received PubSub notification, asking Buildbucket for the build status
[20:03:53.821] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:50.496] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:2:0)
[20:04:50.496] Timer tick, asking Buildbucket for the build status
[20:04:50.598] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:50.598] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:4:0) after 1m0s
[20:05:50.607] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:4:0)
[20:05:50.607] Timer tick, asking Buildbucket for the build status
[20:05:50.784] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:50.784] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:5:0) after 1m0s
[20:06:50.783] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:5:0)
[20:06:50.783] Timer tick, asking Buildbucket for the build status
[20:06:50.886] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:6:0) after 1m0s
[20:07:50.952] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:6:0)
[20:07:50.952] Timer tick, asking Buildbucket for the build status
[20:07:51.093] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:51.093] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:7:0) after 1m0s
[20:08:51.157] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:7:0)
[20:08:51.157] Timer tick, asking Buildbucket for the build status
[20:08:51.255] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:51.256] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:8:0) after 1m0s
[20:09:51.274] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:8:0)
[20:09:51.274] Timer tick, asking Buildbucket for the build status
[20:09:51.383] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:51.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:9:0) after 1m0s
[20:10:51.458] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:9:0)
[20:10:51.458] Timer tick, asking Buildbucket for the build status
[20:10:51.571] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:51.571] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:10:0) after 1m0s
[20:11:51.588] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:10:0)
[20:11:51.588] Timer tick, asking Buildbucket for the build status
[20:11:51.673] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:51.673] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:11:0) after 1m0s
[20:12:51.762] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:11:0)
[20:12:51.762] Timer tick, asking Buildbucket for the build status
[20:12:51.838] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:51.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:12:0) after 1m0s
[20:13:51.879] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:12:0)
[20:13:51.879] Timer tick, asking Buildbucket for the build status
[20:13:51.995] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:51.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:13:0) after 1m0s
[20:14:52.041] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:13:0)
[20:14:52.041] Timer tick, asking Buildbucket for the build status
[20:14:52.197] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:52.197] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:14:0) after 1m0s
[20:15:52.287] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:14:0)
[20:15:52.287] Timer tick, asking Buildbucket for the build status
[20:15:52.382] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.382] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:15:0) after 1m0s
[20:16:52.445] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:15:0)
[20:16:52.445] Timer tick, asking Buildbucket for the build status
[20:16:52.559] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:52.559] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:16:0) after 1m0s
[20:17:52.605] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:16:0)
[20:17:52.605] Timer tick, asking Buildbucket for the build status
[20:17:52.686] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:52.686] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:17:0) after 1m0s
[20:18:52.669] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:17:0)
[20:18:52.669] Timer tick, asking Buildbucket for the build status
[20:18:52.789] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:52.789] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:18:0) after 1m0s
[20:19:52.854] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:18:0)
[20:19:52.854] Timer tick, asking Buildbucket for the build status
[20:19:52.932] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:52.932] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:19:0) after 1m0s
[20:20:53.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:19:0)
[20:20:53.018] Timer tick, asking Buildbucket for the build status
[20:20:53.521] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:53.521] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:20:0) after 1m0s
[20:21:53.583] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:20:0)
[20:21:53.583] Timer tick, asking Buildbucket for the build status
[20:21:53.912] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:53.912] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:21:0) after 1m0s
[20:22:54.071] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:21:0)
[20:22:54.071] Timer tick, asking Buildbucket for the build status
[20:22:54.158] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:54.158] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:22:0) after 1m0s
[20:23:54.221] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:22:0)
[20:23:54.221] Timer tick, asking Buildbucket for the build status
[20:23:54.392] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:54.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:23:0) after 1m0s
[20:24:54.488] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:23:0)
[20:24:54.488] Timer tick, asking Buildbucket for the build status
[20:24:54.556] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:54.556] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:24:0) after 1m0s
[20:25:54.589] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:24:0)
[20:25:54.589] Timer tick, asking Buildbucket for the build status
[20:25:54.703] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.703] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:25:0) after 1m0s
[20:26:54.721] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:25:0)
[20:26:54.721] Timer tick, asking Buildbucket for the build status
[20:26:54.834] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:26:0) after 1m0s
[20:27:54.892] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:26:0)
[20:27:54.892] Timer tick, asking Buildbucket for the build status
[20:27:55.026] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:55.026] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:27:0) after 1m0s
[20:28:55.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:27:0)
[20:28:55.087] Timer tick, asking Buildbucket for the build status
[20:28:55.206] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:55.207] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:28:0) after 1m0s
[20:29:55.264] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:28:0)
[20:29:55.264] Timer tick, asking Buildbucket for the build status
[20:29:55.643] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:55.643] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:29:0) after 1m0s
[20:30:55.727] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:29:0)
[20:30:55.727] Timer tick, asking Buildbucket for the build status
[20:30:55.882] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:55.882] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:30:0) after 1m0s
[20:31:55.978] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:30:0)
[20:31:55.978] Timer tick, asking Buildbucket for the build status
[20:31:56.466] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:56.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:31:0) after 1m0s
[20:32:56.443] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:31:0)
[20:32:56.443] Timer tick, asking Buildbucket for the build status
[20:32:56.518] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:56.518] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:32:0) after 1m0s
[20:33:56.563] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:32:0)
[20:33:56.563] Timer tick, asking Buildbucket for the build status
[20:33:56.806] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:56.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:33:0) after 1m0s
[20:34:56.828] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:33:0)
[20:34:56.828] Timer tick, asking Buildbucket for the build status
[20:34:56.935] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:56.935] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:34:0) after 1m0s
[20:35:56.968] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:34:0)
[20:35:56.968] Timer tick, asking Buildbucket for the build status
[20:35:57.095] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:57.095] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:35:0) after 1m0s
[20:36:57.266] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:35:0)
[20:36:57.266] Timer tick, asking Buildbucket for the build status
[20:36:57.377] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:57.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:36:0) after 1m0s
[20:37:57.597] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:36:0)
[20:37:57.597] Timer tick, asking Buildbucket for the build status
[20:37:57.911] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:37:0) after 1m0s
[20:38:58.000] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:37:0)
[20:38:58.000] Timer tick, asking Buildbucket for the build status
[20:38:58.095] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:58.095] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:38:0) after 1m0s
[20:39:58.172] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:38:0)
[20:39:58.172] Timer tick, asking Buildbucket for the build status
[20:39:58.304] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:58.304] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:39:0) after 1m0s
[20:40:58.358] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:39:0)
[20:40:58.358] Timer tick, asking Buildbucket for the build status
[20:40:58.531] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:40:0) after 1m0s
[20:41:58.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:40:0)
[20:41:58.565] Timer tick, asking Buildbucket for the build status
[20:41:58.737] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:58.737] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:41:0) after 1m0s
[20:42:58.837] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:41:0)
[20:42:58.837] Timer tick, asking Buildbucket for the build status
[20:42:58.960] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:58.960] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:42:0) after 1m0s
[20:43:58.989] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:42:0)
[20:43:58.989] Timer tick, asking Buildbucket for the build status
[20:43:59.121] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:59.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:43:0) after 1m0s
[20:44:59.150] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:43:0)
[20:44:59.150] Timer tick, asking Buildbucket for the build status
[20:44:59.247] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.247] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:44:0) after 1m0s
[20:45:59.288] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:44:0)
[20:45:59.288] Timer tick, asking Buildbucket for the build status
[20:45:59.365] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:59.365] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:45:0) after 1m0s
[20:46:59.374] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:45:0)
[20:46:59.374] Timer tick, asking Buildbucket for the build status
[20:46:59.485] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:59.485] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:46:0) after 1m0s
[20:47:59.511] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:46:0)
[20:47:59.511] Timer tick, asking Buildbucket for the build status
[20:47:59.727] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:59.727] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:47:0) after 1m0s
[20:48:59.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:47:0)
[20:48:59.745] Timer tick, asking Buildbucket for the build status
[20:48:59.861] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:59.861] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:48:0) after 1m0s
[20:49:59.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:48:0)
[20:49:59.954] Timer tick, asking Buildbucket for the build status
[20:50:00.310] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:00.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:49:0) after 1m0s
[20:51:01.290] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:49:0)
[20:51:01.290] Timer tick, asking Buildbucket for the build status
[20:51:01.375] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:01.375] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:50:0) after 1m0s
[20:52:02.488] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:50:0)
[20:52:02.489] Timer tick, asking Buildbucket for the build status
[20:52:02.588] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:02.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:51:0) after 1m0s
[20:53:03.504] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:51:0)
[20:53:03.504] Timer tick, asking Buildbucket for the build status
[20:53:03.823] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:03.823] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:52:0) after 1m0s
[20:54:04.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:52:0)
[20:54:04.191] Timer tick, asking Buildbucket for the build status
[20:54:04.478] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:04.478] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:53:0) after 1m0s
[20:55:04.572] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:53:0)
[20:55:04.572] Timer tick, asking Buildbucket for the build status
[20:55:04.649] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:04.649] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:54:0) after 1m0s
[20:56:04.714] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:54:0)
[20:56:04.714] Timer tick, asking Buildbucket for the build status
[20:56:04.814] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:04.814] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:55:0) after 1m0s
[20:57:04.855] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:55:0)
[20:57:04.855] Timer tick, asking Buildbucket for the build status
[20:57:04.945] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:04.945] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:56:0) after 1m0s
[20:58:05.118] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:56:0)
[20:58:05.118] Timer tick, asking Buildbucket for the build status
[20:58:05.283] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:05.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:57:0) after 1m0s
[20:59:05.331] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:57:0)
[20:59:05.331] Timer tick, asking Buildbucket for the build status
[20:59:05.499] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:05.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:58:0) after 1m0s
[21:00:05.595] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:58:0)
[21:00:05.595] Timer tick, asking Buildbucket for the build status
[21:00:05.821] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:05.821] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:59:0) after 1m0s
[21:01:05.917] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:59:0)
[21:01:05.917] Timer tick, asking Buildbucket for the build status
[21:01:06.051] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:06.051] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:60:0) after 1m0s
[21:02:06.107] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:60:0)
[21:02:06.107] Timer tick, asking Buildbucket for the build status
[21:02:06.212] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:06.212] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:61:0) after 1m0s
[21:03:06.233] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:61:0)
[21:03:06.233] Timer tick, asking Buildbucket for the build status
[21:03:06.344] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:06.344] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:62:0) after 1m0s
[21:04:06.407] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:62:0)
[21:04:06.407] Timer tick, asking Buildbucket for the build status
[21:04:06.536] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:06.536] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:63:0) after 1m0s
[21:05:06.549] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:63:0)
[21:05:06.549] Timer tick, asking Buildbucket for the build status
[21:05:06.719] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:06.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:64:0) after 1m0s
[21:06:06.778] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:64:0)
[21:06:06.778] Timer tick, asking Buildbucket for the build status
[21:06:07.036] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:07.036] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:65:0) after 1m0s
[21:07:07.074] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:65:0)
[21:07:07.074] Timer tick, asking Buildbucket for the build status
[21:07:07.225] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:07.225] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:66:0) after 1m0s
[21:08:07.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:66:0)
[21:08:07.286] Timer tick, asking Buildbucket for the build status
[21:08:07.381] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:07.381] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:67:0) after 1m0s
[21:09:07.424] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:67:0)
[21:09:07.424] Timer tick, asking Buildbucket for the build status
[21:09:07.574] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:07.574] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:68:0) after 1m0s
[21:10:07.606] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:68:0)
[21:10:07.606] Timer tick, asking Buildbucket for the build status
[21:10:07.692] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:07.692] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:69:0) after 1m0s
[21:11:07.722] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:69:0)
[21:11:07.722] Timer tick, asking Buildbucket for the build status
[21:11:08.064] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:08.064] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:70:0) after 1m0s
[21:12:08.115] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:70:0)
[21:12:08.115] Timer tick, asking Buildbucket for the build status
[21:12:08.219] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:08.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:71:0) after 1m0s
[21:13:08.249] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:71:0)
[21:13:08.249] Timer tick, asking Buildbucket for the build status
[21:13:08.322] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:08.322] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:72:0) after 1m0s
[21:14:08.307] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:72:0)
[21:14:08.307] Timer tick, asking Buildbucket for the build status
[21:14:08.484] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:08.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:73:0) after 1m0s
[21:15:08.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:73:0)
[21:15:08.565] Timer tick, asking Buildbucket for the build status
[21:15:08.659] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:08.659] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:74:0) after 1m0s
[21:16:08.702] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:74:0)
[21:16:08.702] Timer tick, asking Buildbucket for the build status
[21:16:08.773] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:08.773] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:75:0) after 1m0s
[21:17:08.872] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:75:0)
[21:17:08.872] Timer tick, asking Buildbucket for the build status
[21:17:08.965] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:08.965] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:76:0) after 1m0s
[21:18:09.031] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:76:0)
[21:18:09.031] Timer tick, asking Buildbucket for the build status
[21:18:09.136] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:09.136] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:77:0) after 1m0s
[21:19:09.167] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:77:0)
[21:19:09.167] Timer tick, asking Buildbucket for the build status
[21:19:09.508] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:09.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:78:0) after 1m0s
[21:20:09.560] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:78:0)
[21:20:09.560] Timer tick, asking Buildbucket for the build status
[21:20:09.645] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:09.645] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:79:0) after 1m0s
[21:21:09.687] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:79:0)
[21:21:09.687] Timer tick, asking Buildbucket for the build status
[21:21:09.808] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:09.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:80:0) after 1m0s
[21:22:09.839] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:80:0)
[21:22:09.839] Timer tick, asking Buildbucket for the build status
[21:22:09.932] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:09.932] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:81:0) after 1m0s
[21:23:09.957] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:81:0)
[21:23:09.957] Timer tick, asking Buildbucket for the build status
[21:23:10.046] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:10.046] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:82:0) after 1m0s
[21:24:10.105] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:82:0)
[21:24:10.105] Timer tick, asking Buildbucket for the build status
[21:24:10.240] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:10.240] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:83:0) after 1m0s
[21:25:10.283] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:83:0)
[21:25:10.283] Timer tick, asking Buildbucket for the build status
[21:25:13.551] Build 8855110227497822928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:13.551] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9020540289428595952:84:0) after 1m0s
[21:25:42.650] Received PubSub notification, asking Buildbucket for the build status
[21:25:42.735] Build 8855110227497822928: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:25:42.735] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613510741648589",
"created_by": "project:chromium",
"created_ts": "1613505829297437",
"id": "8855110227497822928",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"71310e5a752f4291ad953d4b955e7c40c6905259\"}}",
"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\": \"71310e5a752f4291ad953d4b955e7c40c6905259\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-23-4aw4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7850, \"got_angle_revision\": \"91b6c3b1a2b12f6c834238ed680d82c3de741cd0\", \"got_dawn_revision\": \"71b8c9f4ad6f75edea66e91295015d00237703f6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"71310e5a752f4291ad953d4b955e7c40c6905259\", \"got_revision_cp\": \"refs/heads/master@{#854403}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a421556a7cb5554b6f60eb9a18345bb1b7a23ad2\", \"got_v8_revision\": \"51a9b91e1e5176bb007e749e7f506eee7b5407ba\", \"got_v8_revision_cp\": \"refs/heads/9.0.187@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"71310e5a752f4291ad953d4b955e7c40c6905259\", \"swarm_hashes_refs/heads/master(at){#854403}_without_patch\": {\"android_browsertests\": \"d21fa6ae7d8c7e59d0aa48410f9373b4b3bfce75\", \"chrome_public_test_apk\": \"94b2e202f1c5d08d20353f9f0f552a08ec05b592\", \"content_browsertests\": \"629bcfc0b6f4af06809a9ecba78a6206abb96a2e\", \"content_shell_test_apk\": \"b1db711035c37e80968dd54f37a875dff172e083\", \"content_unittests\": \"4425ccc90b083b51dee156b9c334057366c87dd5\", \"unit_tests\": \"6b2519078f62d5b1b6759ff3f6ebed35da51ec7b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4e62a2152674f2c3254d7105a557302458cce07b755aab1e21dcbd6c7ddeb832_v2\", \"builder_5165e2de7796d7dcb4f0b4d7ef826fa605d2b1188c14fc8becf427ca962c0c8e_v2\", \"builder_6887d226fea03ae8e203d0a1d4c9e197b929dd99abf2bafb38063e06e39bc1ba_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_7f949073f7e904bb9563d7870e146ee8e8e8f26011cb03330fcb60d1fc06c798_v2\", \"builder_8f7e40299f74ff9d94767563834f98f87bf45cac7401cd036aab5fd865cb3b58_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-23-4aw4\"], \"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\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613505832767148",
"status": "COMPLETED",
"status_changed_ts": "1613510742109140",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7850",
"builder:android-bfcache-rel",
"buildset:commit/git/71310e5a752f4291ad953d4b955e7c40c6905259",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71310e5a752f4291ad953d4b955e7c40c6905259",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020540289428595952",
"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/8855110227497822928/+/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:51c542a6d576b210",
"user_agent:luci-scheduler"
],
"updated_ts": "1613510742130585",
"url": "https://ci.chromium.org/b/8855110227497822928",
"utcnow_ts": "1613510742727805"
}
}
[21:25:42.735] Invocation finished in 1h21m54.662755882s with status SUCCEEDED