[06:19:55.649] New invocation is queued and will start shortly
[06:19:56.807] Starting the invocation (attempt 1)
[06:19:56.856] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:19:56.856] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:19:56.856] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034091071091450944",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9034091071091450944",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"gitiles_ref:refs/heads/master"
]
}
[06:19:58.052] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1600582796996599",
"id": "8868661009015448160",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600582797780392",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5124",
"builder:android-bfcache-rel",
"buildset:commit/git/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034091071091450944",
"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/8868661009015448160/+/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": "1600582797780620",
"url": "https://ci.chromium.org/b/8868661009015448160",
"utcnow_ts": "1600582798020698"
}
}
[06:19:58.052] Task URL: https://ci.chromium.org/b/8868661009015448160
[06:19:58.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:2:0) after 1m0s
[06:20:58.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:2:0)
[06:20:58.079] Timer tick, asking Buildbucket for the build status
[06:20:58.394] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:58.394] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:3:0) after 1m0s
[06:21:58.436] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:3:0)
[06:21:58.436] Timer tick, asking Buildbucket for the build status
[06:21:58.524] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:58.524] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:4:0) after 1m0s
[06:22:58.625] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:4:0)
[06:22:58.625] Timer tick, asking Buildbucket for the build status
[06:22:58.714] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:58.714] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:5:0) after 1m0s
[06:23:58.793] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:5:0)
[06:23:58.793] Timer tick, asking Buildbucket for the build status
[06:23:58.924] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:58.924] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:6:0) after 1m0s
[06:24:58.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:6:0)
[06:24:58.946] Timer tick, asking Buildbucket for the build status
[06:24:59.071] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:7:0) after 1m0s
[06:25:59.091] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:7:0)
[06:25:59.091] Timer tick, asking Buildbucket for the build status
[06:25:59.297] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:59.297] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:8:0) after 1m0s
[06:26:59.331] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:8:0)
[06:26:59.331] Timer tick, asking Buildbucket for the build status
[06:26:59.411] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:59.411] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:9:0) after 1m0s
[06:27:59.502] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:9:0)
[06:27:59.502] Timer tick, asking Buildbucket for the build status
[06:27:59.585] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:10:0) after 1m0s
[06:28:59.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:10:0)
[06:28:59.610] Timer tick, asking Buildbucket for the build status
[06:28:59.895] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:59.895] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:11:0) after 1m0s
[06:29:59.968] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:11:0)
[06:29:59.968] Timer tick, asking Buildbucket for the build status
[06:30:00.198] Build 8868661009015448160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:00.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:12:0) after 1m0s
[06:30:20.044] Received PubSub notification, asking Buildbucket for the build status
[06:30:20.349] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:00.201] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:12:0)
[06:31:00.201] Timer tick, asking Buildbucket for the build status
[06:31:00.347] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:00.347] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:14:0) after 1m0s
[06:32:00.377] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:14:0)
[06:32:00.377] Timer tick, asking Buildbucket for the build status
[06:32:00.826] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:00.826] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:15:0) after 1m0s
[06:33:02.175] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:15:0)
[06:33:02.175] Timer tick, asking Buildbucket for the build status
[06:33:02.485] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:02.485] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:16:0) after 1m0s
[06:34:02.514] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:16:0)
[06:34:02.514] Timer tick, asking Buildbucket for the build status
[06:34:02.684] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:02.684] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:17:0) after 1m0s
[06:35:02.732] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:17:0)
[06:35:02.732] Timer tick, asking Buildbucket for the build status
[06:35:02.828] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:02.828] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:18:0) after 1m0s
[06:36:02.868] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:18:0)
[06:36:02.868] Timer tick, asking Buildbucket for the build status
[06:36:03.088] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:03.088] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:19:0) after 1m0s
[06:37:03.111] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:19:0)
[06:37:03.111] Timer tick, asking Buildbucket for the build status
[06:37:03.175] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:03.175] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:20:0) after 1m0s
[06:38:03.210] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:20:0)
[06:38:03.210] Timer tick, asking Buildbucket for the build status
[06:38:03.333] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:03.333] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:21:0) after 1m0s
[06:39:03.356] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:21:0)
[06:39:03.356] Timer tick, asking Buildbucket for the build status
[06:39:03.432] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.432] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:22:0) after 1m0s
[06:40:03.460] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:22:0)
[06:40:03.460] Timer tick, asking Buildbucket for the build status
[06:40:03.718] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:03.718] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:23:0) after 1m0s
[06:41:03.781] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:23:0)
[06:41:03.781] Timer tick, asking Buildbucket for the build status
[06:41:03.853] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:03.853] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:24:0) after 1m0s
[06:42:03.832] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:24:0)
[06:42:03.832] Timer tick, asking Buildbucket for the build status
[06:42:03.908] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:03.908] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:25:0) after 1m0s
[06:43:03.976] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:25:0)
[06:43:03.976] Timer tick, asking Buildbucket for the build status
[06:43:04.141] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:04.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:26:0) after 1m0s
[06:44:04.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:26:0)
[06:44:04.312] Timer tick, asking Buildbucket for the build status
[06:44:04.416] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:04.416] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:27:0) after 1m0s
[06:45:04.483] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:27:0)
[06:45:04.483] Timer tick, asking Buildbucket for the build status
[06:45:04.621] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:04.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:28:0) after 1m0s
[06:46:04.712] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:28:0)
[06:46:04.712] Timer tick, asking Buildbucket for the build status
[06:46:04.793] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:29:0) after 1m0s
[06:47:04.826] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:29:0)
[06:47:04.826] Timer tick, asking Buildbucket for the build status
[06:47:04.976] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:04.976] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:30:0) after 1m0s
[06:48:05.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:30:0)
[06:48:05.044] Timer tick, asking Buildbucket for the build status
[06:48:05.364] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:05.364] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:31:0) after 1m0s
[06:49:05.418] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:31:0)
[06:49:05.418] Timer tick, asking Buildbucket for the build status
[06:49:05.520] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:05.520] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:32:0) after 1m0s
[06:50:05.572] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:32:0)
[06:50:05.572] Timer tick, asking Buildbucket for the build status
[06:50:05.691] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:05.692] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:33:0) after 1m0s
[06:51:05.763] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:33:0)
[06:51:05.763] Timer tick, asking Buildbucket for the build status
[06:51:05.927] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:05.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:34:0) after 1m0s
[06:52:06.049] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:34:0)
[06:52:06.049] Timer tick, asking Buildbucket for the build status
[06:52:06.153] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:06.153] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:35:0) after 1m0s
[06:53:06.227] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:35:0)
[06:53:06.227] Timer tick, asking Buildbucket for the build status
[06:53:06.301] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.301] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:36:0) after 1m0s
[06:54:06.332] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:36:0)
[06:54:06.332] Timer tick, asking Buildbucket for the build status
[06:54:06.392] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:06.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:37:0) after 1m0s
[06:55:06.483] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:37:0)
[06:55:06.483] Timer tick, asking Buildbucket for the build status
[06:55:06.885] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:06.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:38:0) after 1m0s
[06:56:06.908] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:38:0)
[06:56:06.908] Timer tick, asking Buildbucket for the build status
[06:56:07.014] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:07.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:39:0) after 1m0s
[06:57:07.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:39:0)
[06:57:07.036] Timer tick, asking Buildbucket for the build status
[06:57:07.234] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:07.234] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:40:0) after 1m0s
[06:58:07.269] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:40:0)
[06:58:07.269] Timer tick, asking Buildbucket for the build status
[06:58:07.374] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:07.374] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:41:0) after 1m0s
[06:59:07.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:41:0)
[06:59:07.784] Timer tick, asking Buildbucket for the build status
[06:59:07.856] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:07.856] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:42:0) after 1m0s
[07:00:07.949] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:42:0)
[07:00:07.949] Timer tick, asking Buildbucket for the build status
[07:00:08.381] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:08.381] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:43:0) after 1m0s
[07:01:09.915] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:43:0)
[07:01:09.915] Timer tick, asking Buildbucket for the build status
[07:01:10.298] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:10.298] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:44:0) after 1m0s
[07:02:10.409] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:44:0)
[07:02:10.409] Timer tick, asking Buildbucket for the build status
[07:02:10.469] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:10.469] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:45:0) after 1m0s
[07:03:10.547] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:45:0)
[07:03:10.548] Timer tick, asking Buildbucket for the build status
[07:03:10.636] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:10.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:46:0) after 1m0s
[07:04:10.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:46:0)
[07:04:10.745] Timer tick, asking Buildbucket for the build status
[07:04:10.914] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:10.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:47:0) after 1m0s
[07:05:11.256] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:47:0)
[07:05:11.256] Timer tick, asking Buildbucket for the build status
[07:05:11.373] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:11.373] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:48:0) after 1m0s
[07:06:11.456] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:48:0)
[07:06:11.456] Timer tick, asking Buildbucket for the build status
[07:06:11.523] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:11.523] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:49:0) after 1m0s
[07:07:11.723] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:49:0)
[07:07:11.723] Timer tick, asking Buildbucket for the build status
[07:07:11.898] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:11.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:50:0) after 1m0s
[07:08:11.927] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:50:0)
[07:08:11.927] Timer tick, asking Buildbucket for the build status
[07:08:12.025] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.025] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:51:0) after 1m0s
[07:09:12.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:51:0)
[07:09:12.061] Timer tick, asking Buildbucket for the build status
[07:09:12.129] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.129] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:52:0) after 1m0s
[07:10:12.158] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:52:0)
[07:10:12.158] Timer tick, asking Buildbucket for the build status
[07:10:12.458] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:12.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:53:0) after 1m0s
[07:11:12.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:53:0)
[07:11:12.455] Timer tick, asking Buildbucket for the build status
[07:11:12.527] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:12.527] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:54:0) after 1m0s
[07:12:12.558] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:54:0)
[07:12:12.558] Timer tick, asking Buildbucket for the build status
[07:12:12.693] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:12.693] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:55:0) after 1m0s
[07:13:12.717] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:55:0)
[07:13:12.717] Timer tick, asking Buildbucket for the build status
[07:13:12.775] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:12.775] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:56:0) after 1m0s
[07:14:12.823] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:56:0)
[07:14:12.823] Timer tick, asking Buildbucket for the build status
[07:14:12.902] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:12.902] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:57:0) after 1m0s
[07:15:12.936] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:57:0)
[07:15:12.936] Timer tick, asking Buildbucket for the build status
[07:15:13.242] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:58:0) after 1m0s
[07:16:13.285] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:58:0)
[07:16:13.285] Timer tick, asking Buildbucket for the build status
[07:16:13.576] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:13.576] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:59:0) after 1m0s
[07:17:20.811] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:59:0)
[07:17:20.811] Timer tick, asking Buildbucket for the build status
[07:17:20.872] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:20.872] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:60:0) after 1m0s
[07:18:21.092] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:60:0)
[07:18:21.092] Timer tick, asking Buildbucket for the build status
[07:18:21.173] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:21.173] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:61:0) after 1m0s
[07:19:21.490] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:61:0)
[07:19:21.490] Timer tick, asking Buildbucket for the build status
[07:19:21.578] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:21.578] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:62:0) after 1m0s
[07:20:21.615] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:62:0)
[07:20:21.615] Timer tick, asking Buildbucket for the build status
[07:20:22.027] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:22.027] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:63:0) after 1m0s
[07:21:22.189] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:63:0)
[07:21:22.189] Timer tick, asking Buildbucket for the build status
[07:21:22.265] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:22.265] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:64:0) after 1m0s
[07:22:22.312] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:64:0)
[07:22:22.312] Timer tick, asking Buildbucket for the build status
[07:22:22.372] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:22.372] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:65:0) after 1m0s
[07:23:22.450] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:65:0)
[07:23:22.451] Timer tick, asking Buildbucket for the build status
[07:23:22.676] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:22.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:66:0) after 1m0s
[07:24:22.740] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:66:0)
[07:24:22.740] Timer tick, asking Buildbucket for the build status
[07:24:22.846] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:22.846] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:67:0) after 1m0s
[07:25:22.887] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:67:0)
[07:25:22.887] Timer tick, asking Buildbucket for the build status
[07:25:23.004] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:23.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:68:0) after 1m0s
[07:26:23.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:68:0)
[07:26:23.087] Timer tick, asking Buildbucket for the build status
[07:26:23.311] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:23.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:69:0) after 1m0s
[07:27:23.358] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:69:0)
[07:27:23.358] Timer tick, asking Buildbucket for the build status
[07:27:23.434] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:23.434] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:70:0) after 1m0s
[07:28:23.458] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:70:0)
[07:28:23.458] Timer tick, asking Buildbucket for the build status
[07:28:23.517] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:23.517] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:71:0) after 1m0s
[07:29:23.564] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:71:0)
[07:29:23.564] Timer tick, asking Buildbucket for the build status
[07:29:23.642] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:23.642] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:72:0) after 1m0s
[07:30:23.670] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:72:0)
[07:30:23.670] Timer tick, asking Buildbucket for the build status
[07:30:23.852] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:23.852] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:73:0) after 1m0s
[07:31:24.490] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:73:0)
[07:31:24.490] Timer tick, asking Buildbucket for the build status
[07:31:24.582] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:24.582] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:74:0) after 1m0s
[07:32:24.722] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:74:0)
[07:32:24.722] Timer tick, asking Buildbucket for the build status
[07:32:24.785] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:24.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:75:0) after 1m0s
[07:33:24.908] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:75:0)
[07:33:24.908] Timer tick, asking Buildbucket for the build status
[07:33:24.979] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:24.979] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:76:0) after 1m0s
[07:34:25.041] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:76:0)
[07:34:25.041] Timer tick, asking Buildbucket for the build status
[07:34:25.105] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:25.105] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:77:0) after 1m0s
[07:35:25.172] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:77:0)
[07:35:25.172] Timer tick, asking Buildbucket for the build status
[07:35:25.289] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:25.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:78:0) after 1m0s
[07:36:25.411] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:78:0)
[07:36:25.411] Timer tick, asking Buildbucket for the build status
[07:36:25.876] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:25.876] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:79:0) after 1m0s
[07:37:26.060] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:79:0)
[07:37:26.060] Timer tick, asking Buildbucket for the build status
[07:37:26.147] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:26.147] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:80:0) after 1m0s
[07:38:26.292] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:80:0)
[07:38:26.292] Timer tick, asking Buildbucket for the build status
[07:38:26.397] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:26.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:81:0) after 1m0s
[07:39:26.449] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:81:0)
[07:39:26.450] Timer tick, asking Buildbucket for the build status
[07:39:26.542] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:26.542] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:82:0) after 1m0s
[07:40:26.640] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:82:0)
[07:40:26.640] Timer tick, asking Buildbucket for the build status
[07:40:26.756] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:26.756] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:83:0) after 1m0s
[07:41:26.808] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:83:0)
[07:41:26.808] Timer tick, asking Buildbucket for the build status
[07:41:27.281] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:27.281] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:84:0) after 1m0s
[07:42:27.352] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:84:0)
[07:42:27.352] Timer tick, asking Buildbucket for the build status
[07:42:27.538] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:27.539] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:85:0) after 1m0s
[07:43:27.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:85:0)
[07:43:27.609] Timer tick, asking Buildbucket for the build status
[07:43:28.035] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:28.035] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:86:0) after 1m0s
[07:44:28.215] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:86:0)
[07:44:28.215] Timer tick, asking Buildbucket for the build status
[07:44:28.706] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:28.706] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:87:0) after 1m0s
[07:45:28.872] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:87:0)
[07:45:28.872] Timer tick, asking Buildbucket for the build status
[07:45:29.157] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:29.157] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:88:0) after 1m0s
[07:46:29.333] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:88:0)
[07:46:29.333] Timer tick, asking Buildbucket for the build status
[07:46:29.809] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:29.809] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:89:0) after 1m0s
[07:47:30.005] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:89:0)
[07:47:30.005] Timer tick, asking Buildbucket for the build status
[07:47:30.239] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:30.239] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:90:0) after 1m0s
[07:48:30.329] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:90:0)
[07:48:30.329] Timer tick, asking Buildbucket for the build status
[07:48:30.492] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:30.492] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:91:0) after 1m0s
[07:49:30.707] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:91:0)
[07:49:30.707] Timer tick, asking Buildbucket for the build status
[07:49:30.779] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:30.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:92:0) after 1m0s
[07:50:30.885] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:92:0)
[07:50:30.885] Timer tick, asking Buildbucket for the build status
[07:50:30.986] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:30.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:93:0) after 1m0s
[07:51:31.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:93:0)
[07:51:31.039] Timer tick, asking Buildbucket for the build status
[07:51:31.391] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:31.391] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:94:0) after 1m0s
[07:52:31.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:94:0)
[07:52:31.479] Timer tick, asking Buildbucket for the build status
[07:52:31.554] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:31.554] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:95:0) after 1m0s
[07:53:31.668] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:95:0)
[07:53:31.668] Timer tick, asking Buildbucket for the build status
[07:53:31.781] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:31.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:96:0) after 1m0s
[07:54:31.874] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:96:0)
[07:54:31.874] Timer tick, asking Buildbucket for the build status
[07:54:31.998] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:31.998] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:97:0) after 1m0s
[07:55:32.036] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:97:0)
[07:55:32.036] Timer tick, asking Buildbucket for the build status
[07:55:32.319] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:32.319] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:98:0) after 1m0s
[07:56:32.386] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:98:0)
[07:56:32.386] Timer tick, asking Buildbucket for the build status
[07:56:32.632] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:32.632] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:99:0) after 1m0s
[07:57:32.677] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:99:0)
[07:57:32.677] Timer tick, asking Buildbucket for the build status
[07:57:32.898] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:32.898] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:100:0) after 1m0s
[07:58:32.952] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:100:0)
[07:58:32.953] Timer tick, asking Buildbucket for the build status
[07:58:33.693] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:33.693] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:101:0) after 1m0s
[07:59:33.714] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:101:0)
[07:59:33.714] Timer tick, asking Buildbucket for the build status
[07:59:33.888] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:33.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:102:0) after 1m0s
[08:00:33.886] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:102:0)
[08:00:33.886] Timer tick, asking Buildbucket for the build status
[08:00:34.558] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:34.558] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:103:0) after 1m0s
[08:01:34.603] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:103:0)
[08:01:34.603] Timer tick, asking Buildbucket for the build status
[08:01:34.695] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:34.695] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:104:0) after 1m0s
[08:02:34.807] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:104:0)
[08:02:34.807] Timer tick, asking Buildbucket for the build status
[08:02:34.972] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:34.972] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:105:0) after 1m0s
[08:03:35.059] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:105:0)
[08:03:35.059] Timer tick, asking Buildbucket for the build status
[08:03:35.123] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:35.123] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:106:0) after 1m0s
[08:04:35.159] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:106:0)
[08:04:35.159] Timer tick, asking Buildbucket for the build status
[08:04:35.231] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:35.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:107:0) after 1m0s
[08:05:35.305] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:107:0)
[08:05:35.305] Timer tick, asking Buildbucket for the build status
[08:05:35.754] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:35.754] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:108:0) after 1m0s
[08:06:35.816] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:108:0)
[08:06:35.816] Timer tick, asking Buildbucket for the build status
[08:06:36.420] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:36.421] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:109:0) after 1m0s
[08:07:36.488] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:109:0)
[08:07:36.488] Timer tick, asking Buildbucket for the build status
[08:07:36.637] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:36.637] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:110:0) after 1m0s
[08:08:36.674] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:110:0)
[08:08:36.674] Timer tick, asking Buildbucket for the build status
[08:08:36.739] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:36.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:111:0) after 1m0s
[08:09:36.771] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:111:0)
[08:09:36.771] Timer tick, asking Buildbucket for the build status
[08:09:36.844] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:36.844] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:112:0) after 1m0s
[08:10:36.872] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:112:0)
[08:10:36.872] Timer tick, asking Buildbucket for the build status
[08:10:37.198] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:37.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:113:0) after 1m0s
[08:11:37.186] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:113:0)
[08:11:37.186] Timer tick, asking Buildbucket for the build status
[08:11:37.255] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:37.255] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:114:0) after 1m0s
[08:12:37.359] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:114:0)
[08:12:37.359] Timer tick, asking Buildbucket for the build status
[08:12:37.426] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:37.426] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:115:0) after 1m0s
[08:13:37.453] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:115:0)
[08:13:37.453] Timer tick, asking Buildbucket for the build status
[08:13:37.535] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:37.535] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:116:0) after 1m0s
[08:14:37.686] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:116:0)
[08:14:37.686] Timer tick, asking Buildbucket for the build status
[08:14:37.764] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:37.764] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:117:0) after 1m0s
[08:15:37.790] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:117:0)
[08:15:37.790] Timer tick, asking Buildbucket for the build status
[08:15:38.224] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:38.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:118:0) after 1m0s
[08:16:38.266] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:118:0)
[08:16:38.266] Timer tick, asking Buildbucket for the build status
[08:16:38.340] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:38.340] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:119:0) after 1m0s
[08:17:38.375] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:119:0)
[08:17:38.375] Timer tick, asking Buildbucket for the build status
[08:17:38.655] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:38.655] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:120:0) after 1m0s
[08:18:38.683] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:120:0)
[08:18:38.683] Timer tick, asking Buildbucket for the build status
[08:18:38.812] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:38.813] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:121:0) after 1m0s
[08:19:38.895] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:121:0)
[08:19:38.895] Timer tick, asking Buildbucket for the build status
[08:19:39.052] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:39.052] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:122:0) after 1m0s
[08:20:39.174] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:122:0)
[08:20:39.174] Timer tick, asking Buildbucket for the build status
[08:20:39.320] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:39.320] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:123:0) after 1m0s
[08:21:39.450] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:123:0)
[08:21:39.450] Timer tick, asking Buildbucket for the build status
[08:21:39.678] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:124:0) after 1m0s
[08:22:39.886] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:124:0)
[08:22:39.886] Timer tick, asking Buildbucket for the build status
[08:22:40.127] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:40.127] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:125:0) after 1m0s
[08:23:40.213] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:125:0)
[08:23:40.213] Timer tick, asking Buildbucket for the build status
[08:23:40.345] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:40.345] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:126:0) after 1m0s
[08:24:40.372] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:126:0)
[08:24:40.372] Timer tick, asking Buildbucket for the build status
[08:24:40.514] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:40.514] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:127:0) after 1m0s
[08:25:40.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:127:0)
[08:25:40.565] Timer tick, asking Buildbucket for the build status
[08:25:40.675] Build 8868661009015448160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:40.675] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9034091071091450944:128:0) after 1m0s
[08:26:28.849] Received PubSub notification, asking Buildbucket for the build status
[08:26:28.936] Build 8868661009015448160: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:26:28.936] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1600590387780595",
"created_by": "project:chromium",
"created_ts": "1600582796996599",
"failure_reason": "BUILD_FAILURE",
"id": "8868661009015448160",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.ntp.cards.promo.HomepagePromoTest#testDismiss_SwipeToDismiss\\n\\n- org.chromium.chrome.browser.flags.FieldTrialsInstrumentationTest#testTwoFeaturesWithDifferentTrialGroupsAndMutipleTypesOfValues\\n\\n- org.chromium.chrome.browser.download.DownloadTest#testDuplicateHttpPostDownload_Cancel__UseDownloadOfflineContentProviderEnabled\\n\\n- org.chromium.chrome.browser.offlinepages.indicator.OfflineIndicatorControllerTest#testReshowOfflineIndicatorWhenResumed\"}, \"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\": \"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\", \"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-70-bndx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 5124, \"got_angle_revision\": \"76e90947a8e421308dff9d00c4955717c6667f12\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\", \"got_revision_cp\": \"refs/heads/master@{#808733}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"80dc1e8055bca07e8da6162bace5ee87f23faccf\", \"got_v8_revision_cp\": \"refs/heads/8.7.142@{#1}\", \"got_webrtc_revision\": \"14dbf82961c32ae9133bb672c88e146942062ada\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32143}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b8484de5c3ca5911513ca00a7f905f4095ec4bc\", \"swarm_hashes\": {\"android_browsertests\": \"f64b72dca010c0b78c0ef1e38df68c5c037d1c2b\", \"chrome_public_test_apk\": \"a7b8a8f58ab1c96f03fbb0ac957dce7b554fb897\", \"content_browsertests\": \"e6567c3892c6765839e95529e2138bfb34b90fba\", \"content_shell_test_apk\": \"7ad9b58f53015380e6b72007b5dc576d706a039e\", \"content_unittests\": \"024abf9d9a718e7fbc995731401c6171013f2273\", \"unit_tests\": \"26e57ca65a011c17cdd360040ed3ac36788de854\"}, \"swarm_hashes_refs/heads/master(at){#808733}_without_patch\": {\"android_browsertests\": \"f64b72dca010c0b78c0ef1e38df68c5c037d1c2b\", \"chrome_public_test_apk\": \"a7b8a8f58ab1c96f03fbb0ac957dce7b554fb897\", \"content_browsertests\": \"e6567c3892c6765839e95529e2138bfb34b90fba\", \"content_shell_test_apk\": \"7ad9b58f53015380e6b72007b5dc576d706a039e\", \"content_unittests\": \"024abf9d9a718e7fbc995731401c6171013f2273\", \"unit_tests\": \"26e57ca65a011c17cdd360040ed3ac36788de854\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_510bb217f1fbd7cfc6f82b4f5538258f70582616cab4052bd10c7cd8994fe33a_v2\", \"builder_609b54f795c46f6da98bce3e3c3edeb2c2e2d72c7759cc5040a86edccd734c7c_v2\", \"builder_f751f07edf7faf91ea99508ca8104b26263f6868ef0141b1cd6ff823c8c6abe6_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-70-bndx\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5531-8ab19d9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.browser.ntp.cards.promo.HomepagePromoTest#testDismiss_SwipeToDismiss\\n\\n- org.chromium.chrome.browser.flags.FieldTrialsInstrumentationTest#testTwoFeaturesWithDifferentTrialGroupsAndMutipleTypesOfValues\\n\\n- org.chromium.chrome.browser.download.DownloadTest#testDuplicateHttpPostDownload_Cancel__UseDownloadOfflineContentProviderEnabled\\n\\n- org.chromium.chrome.browser.offlinepages.indicator.OfflineIndicatorControllerTest#testReshowOfflineIndicatorWhenResumed\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600583419086750",
"status": "COMPLETED",
"status_changed_ts": "1600583419598566",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/5124",
"builder:android-bfcache-rel",
"buildset:commit/git/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b8484de5c3ca5911513ca00a7f905f4095ec4bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034091071091450944",
"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/8868661009015448160/+/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:4ec2fcd2d4edf210",
"user_agent:luci-scheduler"
],
"updated_ts": "1600590388227587",
"url": "https://ci.chromium.org/b/8868661009015448160",
"utcnow_ts": "1600590388925615"
}
}
[08:26:28.936] Invocation finished in 2h6m33.302889897s with status FAILED