[08:21:59.523] New invocation is queued and will start shortly
[08:22:01.229] Starting the invocation (attempt 1)
[08:22:01.271] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:22:01.271] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:22:01.272] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036529509544252080",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"226cecc945e75a8022d6bc73d3cbcbc2546b9eaa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9036529509544252080",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"gitiles_ref:refs/heads/master"
]
}
[08:22:02.520] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598257321410152",
"id": "8871099446902986016",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"226cecc945e75a8022d6bc73d3cbcbc2546b9eaa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598257322314455",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4656",
"builder:android-bfcache-rel",
"buildset:commit/git/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036529509544252080",
"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/8871099446902986016/+/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": "1598257322314939",
"url": "https://ci.chromium.org/b/8871099446902986016",
"utcnow_ts": "1598257322508097"
}
}
[08:22:02.520] Task URL: https://ci.chromium.org/b/8871099446902986016
[08:22:02.520] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:2:0) after 1m0s
[08:22:44.402] Received PubSub notification, asking Buildbucket for the build status
[08:22:44.480] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:03.203] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:2:0)
[08:23:03.203] Timer tick, asking Buildbucket for the build status
[08:23:03.341] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:03.341] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:4:0) after 1m0s
[08:24:03.368] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:4:0)
[08:24:03.368] Timer tick, asking Buildbucket for the build status
[08:24:04.516] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:04.516] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:5:0) after 1m0s
[08:25:04.534] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:5:0)
[08:25:04.534] Timer tick, asking Buildbucket for the build status
[08:25:04.652] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:04.652] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:6:0) after 1m0s
[08:26:04.695] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:6:0)
[08:26:04.695] Timer tick, asking Buildbucket for the build status
[08:26:04.927] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:04.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:7:0) after 1m0s
[08:27:04.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:7:0)
[08:27:04.946] Timer tick, asking Buildbucket for the build status
[08:27:05.114] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:05.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:8:0) after 1m0s
[08:28:05.231] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:8:0)
[08:28:05.231] Timer tick, asking Buildbucket for the build status
[08:28:05.984] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:05.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:9:0) after 1m0s
[08:29:06.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:9:0)
[08:29:06.087] Timer tick, asking Buildbucket for the build status
[08:29:06.266] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:06.266] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:10:0) after 1m0s
[08:30:06.349] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:10:0)
[08:30:06.349] Timer tick, asking Buildbucket for the build status
[08:30:06.677] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:06.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:11:0) after 1m0s
[08:31:06.770] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:11:0)
[08:31:06.770] Timer tick, asking Buildbucket for the build status
[08:31:06.930] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:06.930] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:12:0) after 1m0s
[08:32:06.989] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:12:0)
[08:32:06.989] Timer tick, asking Buildbucket for the build status
[08:32:07.145] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:07.146] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:13:0) after 1m0s
[08:33:07.204] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:13:0)
[08:33:07.204] Timer tick, asking Buildbucket for the build status
[08:33:07.850] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:07.850] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:14:0) after 1m0s
[08:34:07.974] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:14:0)
[08:34:07.974] Timer tick, asking Buildbucket for the build status
[08:34:08.055] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:08.056] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:15:0) after 1m0s
[08:35:08.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:15:0)
[08:35:08.083] Timer tick, asking Buildbucket for the build status
[08:35:08.246] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:08.246] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:16:0) after 1m0s
[08:36:08.357] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:16:0)
[08:36:08.357] Timer tick, asking Buildbucket for the build status
[08:36:08.448] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:08.448] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:17:0) after 1m0s
[08:37:08.505] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:17:0)
[08:37:08.505] Timer tick, asking Buildbucket for the build status
[08:37:08.670] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:08.670] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:18:0) after 1m0s
[08:38:08.845] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:18:0)
[08:38:08.845] Timer tick, asking Buildbucket for the build status
[08:38:09.114] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:09.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:19:0) after 1m0s
[08:39:09.150] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:19:0)
[08:39:09.150] Timer tick, asking Buildbucket for the build status
[08:39:09.248] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:09.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:20:0) after 1m0s
[08:40:09.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:20:0)
[08:40:09.303] Timer tick, asking Buildbucket for the build status
[08:40:09.404] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:09.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:21:0) after 1m0s
[08:41:09.393] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:21:0)
[08:41:09.393] Timer tick, asking Buildbucket for the build status
[08:41:09.594] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:09.594] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:22:0) after 1m0s
[08:42:09.655] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:22:0)
[08:42:09.655] Timer tick, asking Buildbucket for the build status
[08:42:11.048] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:11.048] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:23:0) after 1m0s
[08:43:11.084] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:23:0)
[08:43:11.084] Timer tick, asking Buildbucket for the build status
[08:43:11.183] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:11.183] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:24:0) after 1m0s
[08:44:11.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:24:0)
[08:44:11.239] Timer tick, asking Buildbucket for the build status
[08:44:11.689] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:11.689] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:25:0) after 1m0s
[08:45:11.703] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:25:0)
[08:45:11.703] Timer tick, asking Buildbucket for the build status
[08:45:11.809] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:11.809] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:26:0) after 1m0s
[08:46:11.895] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:26:0)
[08:46:11.895] Timer tick, asking Buildbucket for the build status
[08:46:12.255] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:12.255] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:27:0) after 1m0s
[08:47:12.286] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:27:0)
[08:47:12.286] Timer tick, asking Buildbucket for the build status
[08:47:12.954] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:12.954] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:28:0) after 1m0s
[08:48:13.017] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:28:0)
[08:48:13.017] Timer tick, asking Buildbucket for the build status
[08:48:13.299] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:13.299] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:29:0) after 1m0s
[08:49:13.400] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:29:0)
[08:49:13.400] Timer tick, asking Buildbucket for the build status
[08:49:13.588] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:13.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:30:0) after 1m0s
[08:50:13.632] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:30:0)
[08:50:13.632] Timer tick, asking Buildbucket for the build status
[08:50:14.974] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.974] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:31:0) after 1m0s
[08:51:14.992] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:31:0)
[08:51:14.992] Timer tick, asking Buildbucket for the build status
[08:51:15.095] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.095] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:32:0) after 1m0s
[08:52:15.118] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:32:0)
[08:52:15.118] Timer tick, asking Buildbucket for the build status
[08:52:15.389] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:15.389] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:33:0) after 1m0s
[08:53:15.445] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:33:0)
[08:53:15.445] Timer tick, asking Buildbucket for the build status
[08:53:15.752] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:15.752] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:34:0) after 1m0s
[08:54:15.787] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:34:0)
[08:54:15.787] Timer tick, asking Buildbucket for the build status
[08:54:15.995] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:35:0) after 1m0s
[08:55:16.021] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:35:0)
[08:55:16.021] Timer tick, asking Buildbucket for the build status
[08:55:16.253] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:16.253] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:36:0) after 1m0s
[08:56:16.281] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:36:0)
[08:56:16.281] Timer tick, asking Buildbucket for the build status
[08:56:16.487] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:16.487] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:37:0) after 1m0s
[08:57:16.593] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:37:0)
[08:57:16.593] Timer tick, asking Buildbucket for the build status
[08:57:16.730] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.730] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:38:0) after 1m0s
[08:58:16.852] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:38:0)
[08:58:16.852] Timer tick, asking Buildbucket for the build status
[08:58:17.066] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:17.066] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:39:0) after 1m0s
[08:59:17.140] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:39:0)
[08:59:17.140] Timer tick, asking Buildbucket for the build status
[08:59:17.282] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:17.282] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:40:0) after 1m0s
[09:00:17.475] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:40:0)
[09:00:17.475] Timer tick, asking Buildbucket for the build status
[09:00:17.668] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:17.668] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:41:0) after 1m0s
[09:01:17.752] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:41:0)
[09:01:17.752] Timer tick, asking Buildbucket for the build status
[09:01:18.152] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.152] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:42:0) after 1m0s
[09:02:18.294] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:42:0)
[09:02:18.294] Timer tick, asking Buildbucket for the build status
[09:02:18.505] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.505] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:43:0) after 1m0s
[09:03:18.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:43:0)
[09:03:18.644] Timer tick, asking Buildbucket for the build status
[09:03:18.711] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.711] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:44:0) after 1m0s
[09:04:18.737] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:44:0)
[09:04:18.737] Timer tick, asking Buildbucket for the build status
[09:04:18.905] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:18.905] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:45:0) after 1m0s
[09:05:18.987] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:45:0)
[09:05:18.987] Timer tick, asking Buildbucket for the build status
[09:05:19.301] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:19.301] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:46:0) after 1m0s
[09:06:19.342] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:46:0)
[09:06:19.342] Timer tick, asking Buildbucket for the build status
[09:06:19.486] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:19.486] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:47:0) after 1m0s
[09:07:19.476] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:47:0)
[09:07:19.476] Timer tick, asking Buildbucket for the build status
[09:07:19.588] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:48:0) after 1m0s
[09:08:19.601] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:48:0)
[09:08:19.601] Timer tick, asking Buildbucket for the build status
[09:08:19.675] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:19.675] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:49:0) after 1m0s
[09:09:19.681] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:49:0)
[09:09:19.681] Timer tick, asking Buildbucket for the build status
[09:09:20.013] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:20.013] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:50:0) after 1m0s
[09:10:20.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:50:0)
[09:10:20.039] Timer tick, asking Buildbucket for the build status
[09:10:20.269] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:20.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:51:0) after 1m0s
[09:11:20.306] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:51:0)
[09:11:20.306] Timer tick, asking Buildbucket for the build status
[09:11:20.366] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:20.366] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:52:0) after 1m0s
[09:12:20.523] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:52:0)
[09:12:20.523] Timer tick, asking Buildbucket for the build status
[09:12:20.731] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:20.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:53:0) after 1m0s
[09:13:20.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:53:0)
[09:13:20.915] Timer tick, asking Buildbucket for the build status
[09:13:21.913] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:21.913] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:54:0) after 1m0s
[09:14:21.977] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:54:0)
[09:14:21.977] Timer tick, asking Buildbucket for the build status
[09:14:22.148] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:22.148] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:55:0) after 1m0s
[09:15:22.173] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:55:0)
[09:15:22.173] Timer tick, asking Buildbucket for the build status
[09:15:22.328] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:22.328] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:56:0) after 1m0s
[09:16:22.359] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:56:0)
[09:16:22.359] Timer tick, asking Buildbucket for the build status
[09:16:24.525] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:24.525] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:57:0) after 1m0s
[09:17:24.567] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:57:0)
[09:17:24.567] Timer tick, asking Buildbucket for the build status
[09:17:25.066] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:25.066] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:58:0) after 1m0s
[09:18:25.090] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:58:0)
[09:18:25.090] Timer tick, asking Buildbucket for the build status
[09:18:25.258] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:25.258] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:59:0) after 1m0s
[09:19:25.337] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:59:0)
[09:19:25.337] Timer tick, asking Buildbucket for the build status
[09:19:25.452] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:25.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:60:0) after 1m0s
[09:20:25.552] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:60:0)
[09:20:25.552] Timer tick, asking Buildbucket for the build status
[09:20:25.731] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:25.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:61:0) after 1m0s
[09:21:25.779] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:61:0)
[09:21:25.779] Timer tick, asking Buildbucket for the build status
[09:21:26.836] Build 8871099446902986016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:26.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9036529509544252080:62:0) after 1m0s
[09:22:20.777] Received PubSub notification, asking Buildbucket for the build status
[09:22:20.984] Build 8871099446902986016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:22:20.985] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598260939233656",
"created_by": "project:chromium",
"created_ts": "1598257321410152",
"id": "8871099446902986016",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"226cecc945e75a8022d6bc73d3cbcbc2546b9eaa\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-159-s52s\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4656, \"got_angle_revision\": \"4f7edbe1f6f0639e45d41854e631db2fd77a9ae6\", \"got_dawn_revision\": \"ef7447334713d9f22a8129f78d3da9f6ae26cf8b\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"226cecc945e75a8022d6bc73d3cbcbc2546b9eaa\", \"got_revision_cp\": \"refs/heads/master@{#800927}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"622558b02e1a5441c579994831a6338ffaf2de5c\", \"got_v8_revision\": \"1ca342524984084e1352ca5dd07aa9b539ff7768\", \"got_v8_revision_cp\": \"refs/heads/8.7.3@{#1}\", \"got_webrtc_revision\": \"233cb555112c01f24353993f6103407302e3f177\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31980}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"226cecc945e75a8022d6bc73d3cbcbc2546b9eaa\", \"swarm_hashes\": {\"android_browsertests\": \"90eedcd741324ab4c4e46af4103c0b4446f06f3f\", \"chrome_public_test_apk\": \"dd9e6f1c7a5463054c72bc4fab6d5d014a6fa0dc\", \"content_browsertests\": \"b13e291fcc5fdc2a585d801809462f7ef5f403ca\", \"content_shell_test_apk\": \"d397c4046662488253389f048f3c415073be95d4\", \"content_unittests\": \"6a7cd6c3707036edfe39498840b883511e248080\", \"unit_tests\": \"6eb9f8e590ca0d0637a2ee992c875200c31143e6\"}, \"swarm_hashes_refs/heads/master(at){#800927}_without_patch\": {\"android_browsertests\": \"90eedcd741324ab4c4e46af4103c0b4446f06f3f\", \"chrome_public_test_apk\": \"dd9e6f1c7a5463054c72bc4fab6d5d014a6fa0dc\", \"content_browsertests\": \"b13e291fcc5fdc2a585d801809462f7ef5f403ca\", \"content_shell_test_apk\": \"d397c4046662488253389f048f3c415073be95d4\", \"content_unittests\": \"6a7cd6c3707036edfe39498840b883511e248080\", \"unit_tests\": \"6eb9f8e590ca0d0637a2ee992c875200c31143e6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_732d0a94d3457328e37745e93c2c4c5621265786341764808f1b5a11677755d4_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_fae469debe2f1d0994af48449cd7ba153c00bd37a986e79ea415e9cf77d8cedb_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-159-s52s\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598257363084940",
"status": "COMPLETED",
"status_changed_ts": "1598260939777645",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4656",
"builder:android-bfcache-rel",
"buildset:commit/git/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/226cecc945e75a8022d6bc73d3cbcbc2546b9eaa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036529509544252080",
"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/8871099446902986016/+/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:4e3860e2af092510",
"user_agent:luci-scheduler"
],
"updated_ts": "1598260939804527",
"url": "https://ci.chromium.org/b/8871099446902986016",
"utcnow_ts": "1598260940968365"
}
}
[09:22:20.985] Invocation finished in 1h0m21.476746213s with status SUCCEEDED