[21:28:31.511] New invocation is queued and will start shortly
[21:28:35.005] Starting the invocation (attempt 1)
[21:28:35.053] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:28:35.053] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:28:35.053] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037114203928901008",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6d8654481bcf3a28f325ccaa43b54b58a5b34ed2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9037114203928901008",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"gitiles_ref:refs/heads/master"
]
}
[21:28:36.049] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597699715156597",
"id": "8871684139439076400",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6d8654481bcf3a28f325ccaa43b54b58a5b34ed2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597699715788154",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4541",
"builder:android-bfcache-rel",
"buildset:commit/git/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037114203928901008",
"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/8871684139439076400/+/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": "1597699715788327",
"url": "https://ci.chromium.org/b/8871684139439076400",
"utcnow_ts": "1597699716028719"
}
}
[21:28:36.049] Task URL: https://ci.chromium.org/b/8871684139439076400
[21:28:36.050] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:2:0) after 1m0s
[21:29:36.121] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:2:0)
[21:29:36.121] Timer tick, asking Buildbucket for the build status
[21:29:36.242] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:36.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:3:0) after 1m0s
[21:30:36.262] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:3:0)
[21:30:36.262] Timer tick, asking Buildbucket for the build status
[21:30:36.510] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:4:0) after 1m0s
[21:31:36.530] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:4:0)
[21:31:36.530] Timer tick, asking Buildbucket for the build status
[21:31:36.883] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:36.883] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:5:0) after 1m0s
[21:32:36.879] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:5:0)
[21:32:36.879] Timer tick, asking Buildbucket for the build status
[21:32:37.104] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:37.104] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:6:0) after 1m0s
[21:33:37.183] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:6:0)
[21:33:37.183] Timer tick, asking Buildbucket for the build status
[21:33:37.615] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:37.615] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:7:0) after 1m0s
[21:34:37.712] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:7:0)
[21:34:37.712] Timer tick, asking Buildbucket for the build status
[21:34:38.010] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:38.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:8:0) after 1m0s
[21:35:38.039] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:8:0)
[21:35:38.039] Timer tick, asking Buildbucket for the build status
[21:35:38.135] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:38.135] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:9:0) after 1m0s
[21:36:38.228] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:9:0)
[21:36:38.228] Timer tick, asking Buildbucket for the build status
[21:36:38.523] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:38.523] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:10:0) after 1m0s
[21:37:38.607] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:10:0)
[21:37:38.607] Timer tick, asking Buildbucket for the build status
[21:37:38.717] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:38.717] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:11:0) after 1m0s
[21:38:38.769] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:11:0)
[21:38:38.769] Timer tick, asking Buildbucket for the build status
[21:38:38.899] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:38.899] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:12:0) after 1m0s
[21:39:39.003] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:12:0)
[21:39:39.003] Timer tick, asking Buildbucket for the build status
[21:39:39.108] Build 8871684139439076400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:39.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:13:0) after 1m0s
[21:40:02.621] Received PubSub notification, asking Buildbucket for the build status
[21:40:02.980] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:39.184] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:13:0)
[21:40:39.184] Timer tick, asking Buildbucket for the build status
[21:40:40.426] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:15:0) after 1m0s
[21:41:40.498] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:15:0)
[21:41:40.498] Timer tick, asking Buildbucket for the build status
[21:41:40.619] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:40.619] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:16:0) after 1m0s
[21:42:40.635] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:16:0)
[21:42:40.635] Timer tick, asking Buildbucket for the build status
[21:42:40.729] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:40.729] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:17:0) after 1m0s
[21:43:40.758] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:17:0)
[21:43:40.758] Timer tick, asking Buildbucket for the build status
[21:43:40.957] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:40.957] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:18:0) after 1m0s
[21:44:40.981] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:18:0)
[21:44:40.981] Timer tick, asking Buildbucket for the build status
[21:44:41.179] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:41.179] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:19:0) after 1m0s
[21:45:41.262] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:19:0)
[21:45:41.262] Timer tick, asking Buildbucket for the build status
[21:45:41.379] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:41.379] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:20:0) after 1m0s
[21:46:41.451] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:20:0)
[21:46:41.451] Timer tick, asking Buildbucket for the build status
[21:46:41.571] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:41.571] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:21:0) after 1m0s
[21:47:41.617] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:21:0)
[21:47:41.617] Timer tick, asking Buildbucket for the build status
[21:47:41.986] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:41.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:22:0) after 1m0s
[21:48:42.030] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:22:0)
[21:48:42.030] Timer tick, asking Buildbucket for the build status
[21:48:42.187] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:42.188] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:23:0) after 1m0s
[21:49:42.218] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:23:0)
[21:49:42.218] Timer tick, asking Buildbucket for the build status
[21:49:42.299] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:42.299] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:24:0) after 1m0s
[21:50:42.360] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:24:0)
[21:50:42.360] Timer tick, asking Buildbucket for the build status
[21:50:42.430] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:42.430] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:25:0) after 1m0s
[21:51:42.516] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:25:0)
[21:51:42.516] Timer tick, asking Buildbucket for the build status
[21:51:42.600] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:26:0) after 1m0s
[21:52:43.154] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:26:0)
[21:52:43.155] Timer tick, asking Buildbucket for the build status
[21:52:43.237] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:43.237] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:27:0) after 1m0s
[21:53:43.985] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:27:0)
[21:53:43.985] Timer tick, asking Buildbucket for the build status
[21:53:44.140] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:44.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:28:0) after 1m0s
[21:54:44.920] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:28:0)
[21:54:44.920] Timer tick, asking Buildbucket for the build status
[21:54:45.011] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:45.011] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:29:0) after 1m0s
[21:55:45.219] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:29:0)
[21:55:45.219] Timer tick, asking Buildbucket for the build status
[21:55:45.761] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:45.761] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:30:0) after 1m0s
[21:56:46.592] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:30:0)
[21:56:46.592] Timer tick, asking Buildbucket for the build status
[21:56:46.679] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:46.679] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:31:0) after 1m0s
[21:57:46.725] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:31:0)
[21:57:46.725] Timer tick, asking Buildbucket for the build status
[21:57:46.785] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:46.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:32:0) after 1m0s
[21:58:46.812] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:32:0)
[21:58:46.812] Timer tick, asking Buildbucket for the build status
[21:58:46.963] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:46.963] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:33:0) after 1m0s
[21:59:47.251] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:33:0)
[21:59:47.251] Timer tick, asking Buildbucket for the build status
[21:59:47.540] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:47.540] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:34:0) after 1m0s
[22:00:47.874] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:34:0)
[22:00:47.874] Timer tick, asking Buildbucket for the build status
[22:00:49.367] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:49.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:35:0) after 1m0s
[22:01:49.574] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:35:0)
[22:01:49.574] Timer tick, asking Buildbucket for the build status
[22:01:49.728] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:49.728] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:36:0) after 1m0s
[22:02:49.817] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:36:0)
[22:02:49.818] Timer tick, asking Buildbucket for the build status
[22:02:50.178] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:50.178] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:37:0) after 1m0s
[22:03:50.479] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:37:0)
[22:03:50.479] Timer tick, asking Buildbucket for the build status
[22:03:50.652] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:50.652] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:38:0) after 1m0s
[22:04:50.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:38:0)
[22:04:50.943] Timer tick, asking Buildbucket for the build status
[22:04:51.162] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.162] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:39:0) after 1m0s
[22:05:51.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:39:0)
[22:05:51.303] Timer tick, asking Buildbucket for the build status
[22:05:51.376] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:51.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:40:0) after 1m0s
[22:06:51.403] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:40:0)
[22:06:51.403] Timer tick, asking Buildbucket for the build status
[22:06:51.585] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:51.585] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:41:0) after 1m0s
[22:07:51.602] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:41:0)
[22:07:51.602] Timer tick, asking Buildbucket for the build status
[22:07:51.674] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:51.674] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:42:0) after 1m0s
[22:08:51.756] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:42:0)
[22:08:51.756] Timer tick, asking Buildbucket for the build status
[22:08:52.115] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:52.115] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:43:0) after 1m0s
[22:09:52.159] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:43:0)
[22:09:52.159] Timer tick, asking Buildbucket for the build status
[22:09:52.294] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:52.294] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:44:0) after 1m0s
[22:10:52.303] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:44:0)
[22:10:52.303] Timer tick, asking Buildbucket for the build status
[22:10:52.378] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:52.378] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:45:0) after 1m0s
[22:11:52.430] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:45:0)
[22:11:52.430] Timer tick, asking Buildbucket for the build status
[22:11:52.523] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:52.523] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:46:0) after 1m0s
[22:12:52.596] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:46:0)
[22:12:52.596] Timer tick, asking Buildbucket for the build status
[22:12:52.677] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:52.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:47:0) after 1m0s
[22:13:52.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:47:0)
[22:13:52.750] Timer tick, asking Buildbucket for the build status
[22:13:52.904] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:52.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:48:0) after 1m0s
[22:14:52.936] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:48:0)
[22:14:52.936] Timer tick, asking Buildbucket for the build status
[22:14:53.634] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:53.634] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:49:0) after 1m0s
[22:15:53.670] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:49:0)
[22:15:53.670] Timer tick, asking Buildbucket for the build status
[22:15:53.757] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:53.757] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:50:0) after 1m0s
[22:16:53.869] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:50:0)
[22:16:53.869] Timer tick, asking Buildbucket for the build status
[22:16:53.927] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:53.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:51:0) after 1m0s
[22:17:54.013] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:51:0)
[22:17:54.013] Timer tick, asking Buildbucket for the build status
[22:17:54.105] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:54.105] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:52:0) after 1m0s
[22:18:54.290] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:52:0)
[22:18:54.290] Timer tick, asking Buildbucket for the build status
[22:18:54.638] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:54.638] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:53:0) after 1m0s
[22:19:54.727] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:53:0)
[22:19:54.727] Timer tick, asking Buildbucket for the build status
[22:19:54.823] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:54.823] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:54:0) after 1m0s
[22:20:54.950] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:54:0)
[22:20:54.950] Timer tick, asking Buildbucket for the build status
[22:20:55.038] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:55.038] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:55:0) after 1m0s
[22:21:55.102] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:55:0)
[22:21:55.102] Timer tick, asking Buildbucket for the build status
[22:21:55.329] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:55.329] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:56:0) after 1m0s
[22:22:55.365] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:56:0)
[22:22:55.365] Timer tick, asking Buildbucket for the build status
[22:22:55.456] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:55.456] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:57:0) after 1m0s
[22:23:55.467] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:57:0)
[22:23:55.467] Timer tick, asking Buildbucket for the build status
[22:23:55.578] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:55.578] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:58:0) after 1m0s
[22:24:55.610] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:58:0)
[22:24:55.610] Timer tick, asking Buildbucket for the build status
[22:24:55.695] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:55.695] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:59:0) after 1m0s
[22:25:55.718] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:59:0)
[22:25:55.718] Timer tick, asking Buildbucket for the build status
[22:25:57.274] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:57.274] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:60:0) after 1m0s
[22:26:57.340] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:60:0)
[22:26:57.340] Timer tick, asking Buildbucket for the build status
[22:26:57.437] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:57.437] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:61:0) after 1m0s
[22:27:57.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:61:0)
[22:27:57.477] Timer tick, asking Buildbucket for the build status
[22:27:57.593] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:57.593] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:62:0) after 1m0s
[22:28:57.632] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:62:0)
[22:28:57.632] Timer tick, asking Buildbucket for the build status
[22:28:57.863] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:57.863] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:63:0) after 1m0s
[22:29:57.899] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:63:0)
[22:29:57.899] Timer tick, asking Buildbucket for the build status
[22:29:57.966] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:57.966] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:64:0) after 1m0s
[22:30:57.990] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:64:0)
[22:30:57.990] Timer tick, asking Buildbucket for the build status
[22:30:58.063] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:58.064] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:65:0) after 1m0s
[22:31:58.041] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:65:0)
[22:31:58.041] Timer tick, asking Buildbucket for the build status
[22:31:58.166] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:58.166] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:66:0) after 1m0s
[22:32:58.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:66:0)
[22:32:58.206] Timer tick, asking Buildbucket for the build status
[22:32:58.317] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:58.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:67:0) after 1m0s
[22:33:58.387] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:67:0)
[22:33:58.387] Timer tick, asking Buildbucket for the build status
[22:33:58.547] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:58.547] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:68:0) after 1m0s
[22:34:59.565] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:68:0)
[22:34:59.565] Timer tick, asking Buildbucket for the build status
[22:35:00.412] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:69:0) after 1m0s
[22:36:00.442] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:69:0)
[22:36:00.442] Timer tick, asking Buildbucket for the build status
[22:36:00.533] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.533] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:70:0) after 1m0s
[22:37:00.553] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:70:0)
[22:37:00.553] Timer tick, asking Buildbucket for the build status
[22:37:00.637] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:00.637] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:71:0) after 1m0s
[22:38:00.697] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:71:0)
[22:38:00.697] Timer tick, asking Buildbucket for the build status
[22:38:00.810] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:00.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:72:0) after 1m0s
[22:39:00.921] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:72:0)
[22:39:00.921] Timer tick, asking Buildbucket for the build status
[22:39:01.054] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:73:0) after 1m0s
[22:40:01.997] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:73:0)
[22:40:01.997] Timer tick, asking Buildbucket for the build status
[22:40:02.136] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:02.136] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:74:0) after 1m0s
[22:41:02.175] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:74:0)
[22:41:02.175] Timer tick, asking Buildbucket for the build status
[22:41:02.283] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:02.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:75:0) after 1m0s
[22:42:02.646] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:75:0)
[22:42:02.646] Timer tick, asking Buildbucket for the build status
[22:42:02.772] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:02.772] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:76:0) after 1m0s
[22:43:03.222] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:76:0)
[22:43:03.222] Timer tick, asking Buildbucket for the build status
[22:43:03.393] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:03.393] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:77:0) after 1m0s
[22:44:03.459] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:77:0)
[22:44:03.460] Timer tick, asking Buildbucket for the build status
[22:44:04.120] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:04.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:78:0) after 1m0s
[22:45:04.155] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:78:0)
[22:45:04.155] Timer tick, asking Buildbucket for the build status
[22:45:04.662] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:04.662] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:79:0) after 1m0s
[22:46:04.734] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:79:0)
[22:46:04.734] Timer tick, asking Buildbucket for the build status
[22:46:04.821] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:04.821] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:80:0) after 1m0s
[22:47:04.861] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:80:0)
[22:47:04.861] Timer tick, asking Buildbucket for the build status
[22:47:04.990] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:04.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:81:0) after 1m0s
[22:48:05.057] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:81:0)
[22:48:05.057] Timer tick, asking Buildbucket for the build status
[22:48:05.132] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:05.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:82:0) after 1m0s
[22:49:05.279] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:82:0)
[22:49:05.279] Timer tick, asking Buildbucket for the build status
[22:49:05.602] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:05.602] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:83:0) after 1m0s
[22:50:05.849] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:83:0)
[22:50:05.849] Timer tick, asking Buildbucket for the build status
[22:50:05.948] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:05.948] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:84:0) after 1m0s
[22:51:06.356] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:84:0)
[22:51:06.356] Timer tick, asking Buildbucket for the build status
[22:51:06.529] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.529] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:85:0) after 1m0s
[22:52:07.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:85:0)
[22:52:07.044] Timer tick, asking Buildbucket for the build status
[22:52:07.143] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:07.143] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:86:0) after 1m0s
[22:53:07.271] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:86:0)
[22:53:07.271] Timer tick, asking Buildbucket for the build status
[22:53:07.338] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:07.338] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:87:0) after 1m0s
[22:54:07.547] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:87:0)
[22:54:07.548] Timer tick, asking Buildbucket for the build status
[22:54:07.727] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:07.727] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:88:0) after 1m0s
[22:55:07.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:88:0)
[22:55:07.859] Timer tick, asking Buildbucket for the build status
[22:55:08.325] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:08.325] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:89:0) after 1m0s
[22:56:08.321] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:89:0)
[22:56:08.321] Timer tick, asking Buildbucket for the build status
[22:56:08.399] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:08.399] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:90:0) after 1m0s
[22:57:08.476] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:90:0)
[22:57:08.476] Timer tick, asking Buildbucket for the build status
[22:57:08.681] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:08.681] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:91:0) after 1m0s
[22:58:08.706] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:91:0)
[22:58:08.706] Timer tick, asking Buildbucket for the build status
[22:58:09.118] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:09.118] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:92:0) after 1m0s
[22:59:09.184] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:92:0)
[22:59:09.184] Timer tick, asking Buildbucket for the build status
[22:59:09.376] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:93:0) after 1m0s
[23:00:09.427] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:93:0)
[23:00:09.428] Timer tick, asking Buildbucket for the build status
[23:00:10.204] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:10.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:94:0) after 1m0s
[23:01:10.269] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:94:0)
[23:01:10.269] Timer tick, asking Buildbucket for the build status
[23:01:10.472] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:95:0) after 1m0s
[23:02:10.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:95:0)
[23:02:10.527] Timer tick, asking Buildbucket for the build status
[23:02:11.119] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:11.119] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:96:0) after 1m0s
[23:03:11.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:96:0)
[23:03:11.208] Timer tick, asking Buildbucket for the build status
[23:03:11.342] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:11.342] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:97:0) after 1m0s
[23:04:11.455] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:97:0)
[23:04:11.455] Timer tick, asking Buildbucket for the build status
[23:04:11.607] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:11.607] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:98:0) after 1m0s
[23:05:11.679] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:98:0)
[23:05:11.679] Timer tick, asking Buildbucket for the build status
[23:05:11.871] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:11.871] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:99:0) after 1m0s
[23:06:11.902] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:99:0)
[23:06:11.902] Timer tick, asking Buildbucket for the build status
[23:06:12.406] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:12.406] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:100:0) after 1m0s
[23:07:12.465] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:100:0)
[23:07:12.465] Timer tick, asking Buildbucket for the build status
[23:07:12.770] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:12.770] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:101:0) after 1m0s
[23:08:12.799] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:101:0)
[23:08:12.799] Timer tick, asking Buildbucket for the build status
[23:08:12.883] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:12.883] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:102:0) after 1m0s
[23:09:12.992] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:102:0)
[23:09:12.992] Timer tick, asking Buildbucket for the build status
[23:09:13.180] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:13.180] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:103:0) after 1m0s
[23:10:13.216] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:103:0)
[23:10:13.216] Timer tick, asking Buildbucket for the build status
[23:10:13.340] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:13.340] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:104:0) after 1m0s
[23:11:13.437] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:104:0)
[23:11:13.437] Timer tick, asking Buildbucket for the build status
[23:11:13.593] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:13.593] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:105:0) after 1m0s
[23:12:13.644] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:105:0)
[23:12:13.644] Timer tick, asking Buildbucket for the build status
[23:12:13.743] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:13.743] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:106:0) after 1m0s
[23:13:13.804] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:106:0)
[23:13:13.804] Timer tick, asking Buildbucket for the build status
[23:13:13.912] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:13.912] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:107:0) after 1m0s
[23:14:13.941] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:107:0)
[23:14:13.941] Timer tick, asking Buildbucket for the build status
[23:14:15.080] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:15.080] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:108:0) after 1m0s
[23:15:15.083] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:108:0)
[23:15:15.083] Timer tick, asking Buildbucket for the build status
[23:15:16.016] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:16.016] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:109:0) after 1m0s
[23:16:16.038] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:109:0)
[23:16:16.038] Timer tick, asking Buildbucket for the build status
[23:16:16.135] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:16.135] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:110:0) after 1m0s
[23:17:16.178] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:110:0)
[23:17:16.178] Timer tick, asking Buildbucket for the build status
[23:17:16.456] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:16.456] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:111:0) after 1m0s
[23:18:16.514] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:111:0)
[23:18:16.514] Timer tick, asking Buildbucket for the build status
[23:18:16.784] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:16.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:112:0) after 1m0s
[23:19:16.843] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:112:0)
[23:19:16.843] Timer tick, asking Buildbucket for the build status
[23:19:17.175] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:17.175] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:113:0) after 1m0s
[23:20:17.261] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:113:0)
[23:20:17.261] Timer tick, asking Buildbucket for the build status
[23:20:17.597] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:17.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:114:0) after 1m0s
[23:21:17.637] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:114:0)
[23:21:17.637] Timer tick, asking Buildbucket for the build status
[23:21:17.735] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:17.735] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:115:0) after 1m0s
[23:22:17.809] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:115:0)
[23:22:17.809] Timer tick, asking Buildbucket for the build status
[23:22:17.893] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:17.893] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:116:0) after 1m0s
[23:23:17.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:116:0)
[23:23:17.954] Timer tick, asking Buildbucket for the build status
[23:23:18.040] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:18.040] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:117:0) after 1m0s
[23:24:18.076] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:117:0)
[23:24:18.076] Timer tick, asking Buildbucket for the build status
[23:24:18.464] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:18.464] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:118:0) after 1m0s
[23:25:18.527] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:118:0)
[23:25:18.527] Timer tick, asking Buildbucket for the build status
[23:25:18.605] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:18.605] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:119:0) after 1m0s
[23:26:18.676] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:119:0)
[23:26:18.676] Timer tick, asking Buildbucket for the build status
[23:26:18.776] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:18.777] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:120:0) after 1m0s
[23:27:18.843] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:120:0)
[23:27:18.843] Timer tick, asking Buildbucket for the build status
[23:27:18.914] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:18.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:121:0) after 1m0s
[23:28:18.999] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:121:0)
[23:28:18.999] Timer tick, asking Buildbucket for the build status
[23:28:19.127] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:19.127] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:122:0) after 1m0s
[23:29:19.202] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:122:0)
[23:29:19.203] Timer tick, asking Buildbucket for the build status
[23:29:19.343] Build 8871684139439076400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:19.343] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9037114203928901008:123:0) after 1m0s
[23:29:52.615] Received PubSub notification, asking Buildbucket for the build status
[23:29:52.694] Build 8871684139439076400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:29:52.694] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597706991663675",
"created_by": "project:chromium",
"created_ts": "1597699715156597",
"id": "8871684139439076400",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6d8654481bcf3a28f325ccaa43b54b58a5b34ed2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-37-j84p\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 4541, \"got_angle_revision\": \"d63bca58a0852240be34d74e79c77591b7f283f6\", \"got_dawn_revision\": \"988f19e208bbc10cfc34dc01e800ebdcc54d1988\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"6d8654481bcf3a28f325ccaa43b54b58a5b34ed2\", \"got_revision_cp\": \"refs/heads/master@{#798833}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e7cd224e55fa55ba248d8fba0d0dd34b948c12ae\", \"got_v8_revision\": \"0c2650adcc949784fe629ebba7b7255ebe2b48f5\", \"got_v8_revision_cp\": \"refs/heads/8.6.390@{#1}\", \"got_webrtc_revision\": \"c8ac35879c9db21b25143d2075ce37f44b8f1897\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31954}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6d8654481bcf3a28f325ccaa43b54b58a5b34ed2\", \"swarm_hashes\": {\"android_browsertests\": \"5355e5926b1ceb7a02b60350771cf0eeadace9d6\", \"chrome_public_test_apk\": \"f215f2a75b97d9e9b5ad8afaf414344033ac836c\", \"content_browsertests\": \"274eb1d255101b93d3ebed1e0345b7ec9ae6c45a\", \"content_shell_test_apk\": \"aac84e4a03cd816b328b3d94f81c8e9144b8828e\", \"content_unittests\": \"c0aa617724b73ca8d06841149d14d935d07e9be9\", \"unit_tests\": \"2e7678bf3355d084ee934fbab7f64c2b640e6f98\"}, \"swarm_hashes_refs/heads/master(at){#798833}_without_patch\": {\"android_browsertests\": \"5355e5926b1ceb7a02b60350771cf0eeadace9d6\", \"chrome_public_test_apk\": \"f215f2a75b97d9e9b5ad8afaf414344033ac836c\", \"content_browsertests\": \"274eb1d255101b93d3ebed1e0345b7ec9ae6c45a\", \"content_shell_test_apk\": \"aac84e4a03cd816b328b3d94f81c8e9144b8828e\", \"content_unittests\": \"c0aa617724b73ca8d06841149d14d935d07e9be9\", \"unit_tests\": \"2e7678bf3355d084ee934fbab7f64c2b640e6f98\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_6337027b9f5c92bb839aef39a1f908f5aaa6ec745f5c7fe76eeae24bfbb4f02b_v2\", \"builder_781dcb8c5ac4755751737dd30441d8da461a898dc54a9ff1cedb2cb51bef586b_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_98a2c5e0deb03b8d40c31088589742fc2ee427d37737f70c652d17331a8510d3_v2\", \"builder_df0918bfe72974da8c562c0af785205b640d6f631f017829e032ab97ab701012_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-37-j84p\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5488-fc78d20\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1597700401526336",
"status": "COMPLETED",
"status_changed_ts": "1597706992123008",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/4541",
"builder:android-bfcache-rel",
"buildset:commit/git/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6d8654481bcf3a28f325ccaa43b54b58a5b34ed2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037114203928901008",
"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/8871684139439076400/+/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:4e17247c6e1ca210",
"user_agent:luci-scheduler"
],
"updated_ts": "1597706992136717",
"url": "https://ci.chromium.org/b/8871684139439076400",
"utcnow_ts": "1597706992685121"
}
}
[23:29:52.694] Invocation finished in 2h1m21.20411928s with status SUCCEEDED