[22:31:00.167] New invocation is queued and will start shortly
[22:31:01.859] Starting the invocation (attempt 1)
[22:31:01.936] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:01.936] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:01.936] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9045264000149523216",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7443f460587f22e09d0b5e154f35af1cb388bdaf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9045264000149523216",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"gitiles_ref:refs/heads/master"
]
}
[22:31:04.046] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1589927462157198",
"id": "8879833937399583728",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7443f460587f22e09d0b5e154f35af1cb388bdaf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1589927463533211",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/2815",
"builder:android-bfcache-rel",
"buildset:commit/git/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045264000149523216",
"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/8879833937399583728/+/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": "1589927463533385",
"url": "https://ci.chromium.org/b/8879833937399583728",
"utcnow_ts": "1589927464025479"
}
}
[22:31:04.046] Task URL: https://ci.chromium.org/b/8879833937399583728
[22:31:04.046] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:2:0) after 1m0s
[22:32:04.162] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:2:0)
[22:32:04.162] Timer tick, asking Buildbucket for the build status
[22:32:04.315] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:04.315] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:3:0) after 1m0s
[22:33:04.378] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:3:0)
[22:33:04.378] Timer tick, asking Buildbucket for the build status
[22:33:04.501] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:04.501] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:4:0) after 1m0s
[22:34:04.546] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:4:0)
[22:34:04.546] Timer tick, asking Buildbucket for the build status
[22:34:04.676] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:04.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:5:0) after 1m0s
[22:35:04.766] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:5:0)
[22:35:04.766] Timer tick, asking Buildbucket for the build status
[22:35:04.869] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:04.869] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:6:0) after 1m0s
[22:36:04.907] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:6:0)
[22:36:04.907] Timer tick, asking Buildbucket for the build status
[22:36:05.088] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:05.088] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:7:0) after 1m0s
[22:37:05.207] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:7:0)
[22:37:05.207] Timer tick, asking Buildbucket for the build status
[22:37:05.319] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:05.319] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:8:0) after 1m0s
[22:38:05.385] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:8:0)
[22:38:05.385] Timer tick, asking Buildbucket for the build status
[22:38:05.567] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:05.567] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:9:0) after 1m0s
[22:39:05.623] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:9:0)
[22:39:05.623] Timer tick, asking Buildbucket for the build status
[22:39:05.749] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:05.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:10:0) after 1m0s
[22:40:05.813] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:10:0)
[22:40:05.813] Timer tick, asking Buildbucket for the build status
[22:40:05.944] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:05.944] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:11:0) after 1m0s
[22:41:05.975] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:11:0)
[22:41:05.975] Timer tick, asking Buildbucket for the build status
[22:41:06.310] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:06.310] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:12:0) after 1m0s
[22:42:06.363] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:12:0)
[22:42:06.363] Timer tick, asking Buildbucket for the build status
[22:42:06.501] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:06.501] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:13:0) after 1m0s
[22:43:06.587] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:13:0)
[22:43:06.587] Timer tick, asking Buildbucket for the build status
[22:43:06.676] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:06.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:14:0) after 1m0s
[22:44:06.754] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:14:0)
[22:44:06.754] Timer tick, asking Buildbucket for the build status
[22:44:07.223] Build 8879833937399583728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:07.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:15:0) after 1m0s
[22:44:12.300] Received PubSub notification, asking Buildbucket for the build status
[22:44:12.418] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:07.270] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:15:0)
[22:45:07.270] Timer tick, asking Buildbucket for the build status
[22:45:07.438] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:07.438] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:17:0) after 1m0s
[22:46:07.512] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:17:0)
[22:46:07.512] Timer tick, asking Buildbucket for the build status
[22:46:08.042] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:08.042] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:18:0) after 1m0s
[22:47:08.092] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:18:0)
[22:47:08.092] Timer tick, asking Buildbucket for the build status
[22:47:08.444] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:08.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:19:0) after 1m0s
[22:48:08.570] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:19:0)
[22:48:08.570] Timer tick, asking Buildbucket for the build status
[22:48:08.713] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:08.713] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:20:0) after 1m0s
[22:49:08.886] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:20:0)
[22:49:08.886] Timer tick, asking Buildbucket for the build status
[22:49:09.022] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:09.022] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:21:0) after 1m0s
[22:50:09.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:21:0)
[22:50:09.079] Timer tick, asking Buildbucket for the build status
[22:50:09.217] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:09.217] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:22:0) after 1m0s
[22:51:09.212] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:22:0)
[22:51:09.212] Timer tick, asking Buildbucket for the build status
[22:51:09.383] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:09.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:23:0) after 1m0s
[22:52:09.430] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:23:0)
[22:52:09.430] Timer tick, asking Buildbucket for the build status
[22:52:10.311] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:10.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:24:0) after 1m0s
[22:53:10.532] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:24:0)
[22:53:10.532] Timer tick, asking Buildbucket for the build status
[22:53:10.813] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:10.813] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:25:0) after 1m0s
[22:54:10.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:25:0)
[22:54:10.859] Timer tick, asking Buildbucket for the build status
[22:54:11.013] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:11.013] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:26:0) after 1m0s
[22:55:11.071] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:26:0)
[22:55:11.071] Timer tick, asking Buildbucket for the build status
[22:55:11.279] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:11.279] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:27:0) after 1m0s
[22:56:11.364] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:27:0)
[22:56:11.364] Timer tick, asking Buildbucket for the build status
[22:56:11.561] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:11.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:28:0) after 1m0s
[22:57:11.551] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:28:0)
[22:57:11.551] Timer tick, asking Buildbucket for the build status
[22:57:11.948] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:11.948] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:29:0) after 1m0s
[22:58:11.986] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:29:0)
[22:58:11.986] Timer tick, asking Buildbucket for the build status
[22:58:13.216] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:13.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:30:0) after 1m0s
[22:59:13.273] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:30:0)
[22:59:13.273] Timer tick, asking Buildbucket for the build status
[22:59:13.456] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:13.456] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:31:0) after 1m0s
[23:00:13.531] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:31:0)
[23:00:13.531] Timer tick, asking Buildbucket for the build status
[23:00:13.729] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:13.729] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:32:0) after 1m0s
[23:01:13.761] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:32:0)
[23:01:13.761] Timer tick, asking Buildbucket for the build status
[23:01:14.747] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:14.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:33:0) after 1m0s
[23:02:14.755] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:33:0)
[23:02:14.755] Timer tick, asking Buildbucket for the build status
[23:02:14.931] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:34:0) after 1m0s
[23:03:14.916] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:34:0)
[23:03:14.916] Timer tick, asking Buildbucket for the build status
[23:03:15.276] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:15.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:35:0) after 1m0s
[23:04:15.310] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:35:0)
[23:04:15.310] Timer tick, asking Buildbucket for the build status
[23:04:15.490] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:15.490] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:36:0) after 1m0s
[23:05:15.506] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:36:0)
[23:05:15.506] Timer tick, asking Buildbucket for the build status
[23:05:15.631] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:15.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:37:0) after 1m0s
[23:06:15.716] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:37:0)
[23:06:15.716] Timer tick, asking Buildbucket for the build status
[23:06:15.871] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:15.871] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:38:0) after 1m0s
[23:07:16.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:38:0)
[23:07:16.191] Timer tick, asking Buildbucket for the build status
[23:07:16.392] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:16.392] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:39:0) after 1m0s
[23:08:16.784] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:39:0)
[23:08:16.784] Timer tick, asking Buildbucket for the build status
[23:08:16.969] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:16.969] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:40:0) after 1m0s
[23:09:17.133] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:40:0)
[23:09:17.133] Timer tick, asking Buildbucket for the build status
[23:09:17.476] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:17.476] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:41:0) after 1m0s
[23:10:17.609] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:41:0)
[23:10:17.609] Timer tick, asking Buildbucket for the build status
[23:10:17.795] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:17.795] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:42:0) after 1m0s
[23:11:18.129] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:42:0)
[23:11:18.129] Timer tick, asking Buildbucket for the build status
[23:11:18.238] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.238] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:43:0) after 1m0s
[23:12:18.360] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:43:0)
[23:12:18.360] Timer tick, asking Buildbucket for the build status
[23:12:18.490] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:18.490] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:44:0) after 1m0s
[23:13:18.586] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:44:0)
[23:13:18.586] Timer tick, asking Buildbucket for the build status
[23:13:19.677] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:19.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:45:0) after 1m0s
[23:14:19.856] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:45:0)
[23:14:19.856] Timer tick, asking Buildbucket for the build status
[23:14:20.004] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:20.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:46:0) after 1m0s
[23:15:20.095] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:46:0)
[23:15:20.095] Timer tick, asking Buildbucket for the build status
[23:15:20.259] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.259] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:47:0) after 1m0s
[23:16:20.257] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:47:0)
[23:16:20.257] Timer tick, asking Buildbucket for the build status
[23:16:20.412] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:20.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:48:0) after 1m0s
[23:17:20.485] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:48:0)
[23:17:20.485] Timer tick, asking Buildbucket for the build status
[23:17:20.591] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:20.591] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:49:0) after 1m0s
[23:18:20.673] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:49:0)
[23:18:20.673] Timer tick, asking Buildbucket for the build status
[23:18:20.950] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:20.950] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:50:0) after 1m0s
[23:19:20.958] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:50:0)
[23:19:20.958] Timer tick, asking Buildbucket for the build status
[23:19:21.121] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:21.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:51:0) after 1m0s
[23:20:21.259] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:51:0)
[23:20:21.259] Timer tick, asking Buildbucket for the build status
[23:20:21.423] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:21.423] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:52:0) after 1m0s
[23:21:21.557] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:52:0)
[23:21:21.557] Timer tick, asking Buildbucket for the build status
[23:21:21.830] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.830] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:53:0) after 1m0s
[23:22:21.944] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:53:0)
[23:22:21.944] Timer tick, asking Buildbucket for the build status
[23:22:22.123] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:22.123] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:54:0) after 1m0s
[23:23:22.205] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:54:0)
[23:23:22.205] Timer tick, asking Buildbucket for the build status
[23:23:22.340] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:22.340] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:55:0) after 1m0s
[23:24:22.456] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:55:0)
[23:24:22.456] Timer tick, asking Buildbucket for the build status
[23:24:24.273] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:56:0) after 1m0s
[23:25:24.637] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:56:0)
[23:25:24.637] Timer tick, asking Buildbucket for the build status
[23:25:25.028] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:25.028] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:57:0) after 1m0s
[23:26:25.079] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:57:0)
[23:26:25.079] Timer tick, asking Buildbucket for the build status
[23:26:25.237] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:25.237] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:58:0) after 1m0s
[23:27:25.307] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:58:0)
[23:27:25.307] Timer tick, asking Buildbucket for the build status
[23:27:25.466] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:25.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:59:0) after 1m0s
[23:28:25.493] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:59:0)
[23:28:25.494] Timer tick, asking Buildbucket for the build status
[23:28:25.641] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:25.641] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:60:0) after 1m0s
[23:29:25.667] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:60:0)
[23:29:25.667] Timer tick, asking Buildbucket for the build status
[23:29:25.945] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:25.945] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:61:0) after 1m0s
[23:30:26.034] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:61:0)
[23:30:26.034] Timer tick, asking Buildbucket for the build status
[23:30:26.221] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:26.221] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:62:0) after 1m0s
[23:31:26.384] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:62:0)
[23:31:26.384] Timer tick, asking Buildbucket for the build status
[23:31:26.702] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:63:0) after 1m0s
[23:32:26.781] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:63:0)
[23:32:26.781] Timer tick, asking Buildbucket for the build status
[23:32:26.977] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:26.978] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:64:0) after 1m0s
[23:33:27.166] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:64:0)
[23:33:27.166] Timer tick, asking Buildbucket for the build status
[23:33:27.488] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:27.488] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:65:0) after 1m0s
[23:34:27.803] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:65:0)
[23:34:27.803] Timer tick, asking Buildbucket for the build status
[23:34:27.994] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:27.994] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:66:0) after 1m0s
[23:35:28.156] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:66:0)
[23:35:28.156] Timer tick, asking Buildbucket for the build status
[23:35:28.654] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:28.654] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:67:0) after 1m0s
[23:36:28.820] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:67:0)
[23:36:28.820] Timer tick, asking Buildbucket for the build status
[23:36:29.309] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:29.309] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:68:0) after 1m0s
[23:37:29.475] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:68:0)
[23:37:29.475] Timer tick, asking Buildbucket for the build status
[23:37:29.737] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:29.737] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:69:0) after 1m0s
[23:38:29.811] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:69:0)
[23:38:29.811] Timer tick, asking Buildbucket for the build status
[23:38:30.231] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:30.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:70:0) after 1m0s
[23:39:30.356] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:70:0)
[23:39:30.356] Timer tick, asking Buildbucket for the build status
[23:39:30.652] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:30.652] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:71:0) after 1m0s
[23:40:30.708] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:71:0)
[23:40:30.708] Timer tick, asking Buildbucket for the build status
[23:40:32.873] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:32.873] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:72:0) after 1m0s
[23:41:33.199] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:72:0)
[23:41:33.199] Timer tick, asking Buildbucket for the build status
[23:41:33.317] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:33.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:73:0) after 1m0s
[23:42:33.349] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:73:0)
[23:42:33.349] Timer tick, asking Buildbucket for the build status
[23:42:33.478] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:33.478] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:74:0) after 1m0s
[23:43:33.566] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:74:0)
[23:43:33.566] Timer tick, asking Buildbucket for the build status
[23:43:33.751] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:33.751] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:75:0) after 1m0s
[23:44:33.866] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:75:0)
[23:44:33.866] Timer tick, asking Buildbucket for the build status
[23:44:34.104] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:34.104] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:76:0) after 1m0s
[23:45:34.144] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:76:0)
[23:45:34.144] Timer tick, asking Buildbucket for the build status
[23:45:35.063] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:35.063] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:77:0) after 1m0s
[23:46:35.242] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:77:0)
[23:46:35.242] Timer tick, asking Buildbucket for the build status
[23:46:35.465] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:35.465] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:78:0) after 1m0s
[23:47:35.537] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:78:0)
[23:47:35.537] Timer tick, asking Buildbucket for the build status
[23:47:36.838] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:36.838] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:79:0) after 1m0s
[23:48:37.037] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:79:0)
[23:48:37.037] Timer tick, asking Buildbucket for the build status
[23:48:37.727] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:37.727] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:80:0) after 1m0s
[23:49:37.773] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:80:0)
[23:49:37.773] Timer tick, asking Buildbucket for the build status
[23:49:38.380] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:38.380] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:81:0) after 1m0s
[23:50:38.404] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:81:0)
[23:50:38.404] Timer tick, asking Buildbucket for the build status
[23:50:39.723] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:39.723] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:82:0) after 1m0s
[23:51:39.781] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:82:0)
[23:51:39.781] Timer tick, asking Buildbucket for the build status
[23:51:39.915] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:39.915] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:83:0) after 1m0s
[23:52:40.050] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:83:0)
[23:52:40.050] Timer tick, asking Buildbucket for the build status
[23:52:40.317] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:84:0) after 1m0s
[23:53:40.405] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:84:0)
[23:53:40.405] Timer tick, asking Buildbucket for the build status
[23:53:40.586] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:40.586] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:85:0) after 1m0s
[23:54:40.620] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:85:0)
[23:54:40.620] Timer tick, asking Buildbucket for the build status
[23:54:40.871] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:40.871] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:86:0) after 1m0s
[23:55:40.954] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:86:0)
[23:55:40.954] Timer tick, asking Buildbucket for the build status
[23:55:41.134] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:41.134] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:87:0) after 1m0s
[23:56:41.317] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:87:0)
[23:56:41.317] Timer tick, asking Buildbucket for the build status
[23:56:41.494] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:41.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:88:0) after 1m0s
[23:57:41.604] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:88:0)
[23:57:41.604] Timer tick, asking Buildbucket for the build status
[23:57:41.719] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:41.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:89:0) after 1m0s
[23:58:41.843] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:89:0)
[23:58:41.843] Timer tick, asking Buildbucket for the build status
[23:58:42.006] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:42.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:90:0) after 1m0s
[23:59:42.080] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:90:0)
[23:59:42.080] Timer tick, asking Buildbucket for the build status
[23:59:42.312] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.312] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:91:0) after 1m0s
[00:00:42.363] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:91:0)
[00:00:42.363] Timer tick, asking Buildbucket for the build status
[00:00:42.629] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:42.629] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:92:0) after 1m0s
[00:01:42.741] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:92:0)
[00:01:42.741] Timer tick, asking Buildbucket for the build status
[00:01:43.034] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:43.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:93:0) after 1m0s
[00:02:43.061] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:93:0)
[00:02:43.061] Timer tick, asking Buildbucket for the build status
[00:02:43.362] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:43.362] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:94:0) after 1m0s
[00:03:43.444] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:94:0)
[00:03:43.444] Timer tick, asking Buildbucket for the build status
[00:03:43.573] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:43.573] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:95:0) after 1m0s
[00:04:43.649] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:95:0)
[00:04:43.649] Timer tick, asking Buildbucket for the build status
[00:04:44.157] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:44.157] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:96:0) after 1m0s
[00:05:44.214] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:96:0)
[00:05:44.214] Timer tick, asking Buildbucket for the build status
[00:05:44.370] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:44.370] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:97:0) after 1m0s
[00:06:44.463] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:97:0)
[00:06:44.463] Timer tick, asking Buildbucket for the build status
[00:06:44.756] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:98:0) after 1m0s
[00:07:44.745] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:98:0)
[00:07:44.745] Timer tick, asking Buildbucket for the build status
[00:07:45.183] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:45.183] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:99:0) after 1m0s
[00:08:45.281] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:99:0)
[00:08:45.281] Timer tick, asking Buildbucket for the build status
[00:08:45.582] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:45.582] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:100:0) after 1m0s
[00:09:45.654] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:100:0)
[00:09:45.654] Timer tick, asking Buildbucket for the build status
[00:09:45.914] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:45.914] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:101:0) after 1m0s
[00:10:46.080] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:101:0)
[00:10:46.080] Timer tick, asking Buildbucket for the build status
[00:10:46.321] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.323] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:102:0) after 1m0s
[00:11:46.477] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:102:0)
[00:11:46.477] Timer tick, asking Buildbucket for the build status
[00:11:47.749] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:47.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:103:0) after 1m0s
[00:12:47.791] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:103:0)
[00:12:47.791] Timer tick, asking Buildbucket for the build status
[00:12:48.161] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:48.161] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:104:0) after 1m0s
[00:13:48.186] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:104:0)
[00:13:48.186] Timer tick, asking Buildbucket for the build status
[00:13:48.360] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:48.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:105:0) after 1m0s
[00:14:48.407] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:105:0)
[00:14:48.407] Timer tick, asking Buildbucket for the build status
[00:14:48.572] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:48.572] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:106:0) after 1m0s
[00:15:48.874] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:106:0)
[00:15:48.874] Timer tick, asking Buildbucket for the build status
[00:15:49.471] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:49.471] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:107:0) after 1m0s
[00:16:49.618] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:107:0)
[00:16:49.618] Timer tick, asking Buildbucket for the build status
[00:16:50.060] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:50.060] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:108:0) after 1m0s
[00:17:50.247] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:108:0)
[00:17:50.247] Timer tick, asking Buildbucket for the build status
[00:17:50.630] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:50.630] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:109:0) after 1m0s
[00:18:50.757] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:109:0)
[00:18:50.757] Timer tick, asking Buildbucket for the build status
[00:18:52.034] Build 8879833937399583728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:52.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9045264000149523216:110:0) after 1m0s
[00:19:37.235] Received PubSub notification, asking Buildbucket for the build status
[00:19:37.325] Build 8879833937399583728: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:19:37.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1589933976652239",
"created_by": "project:chromium",
"created_ts": "1589927462157198",
"failure_reason": "BUILD_FAILURE",
"id": "8879833937399583728",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7443f460587f22e09d0b5e154f35af1cb388bdaf\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- SitePerProcessBrowserTest.NavigateMainFrameToChildSite\\n\\n- SitePerProcessBrowserTest.ActiveSandboxFlagsRetainedAfterUnload\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- ...24 more failure(s) (28 total)...\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__Instant_NoReturn\\n\\n- ...129 more failure(s) (132 total)...\\n\\n#### ...1 more test(s)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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-82-1pjt\", \"branch\": \"refs/heads/master\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 2815, \"got_angle_revision\": \"cdfc69c7f0f833e99b435715b3c012a2b61e55b1\", \"got_dawn_revision\": \"8d6b021a2a134068bc46a7773ae553f8d957b937\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"7443f460587f22e09d0b5e154f35af1cb388bdaf\", \"got_revision_cp\": \"refs/heads/master@{#770331}\", \"got_swarming_client_revision\": \"7e8636295f4763a5f5aace1e700b17a4af3c81b7\", \"got_swiftshader_revision\": \"b6e8c3f0f4830887d69ba765a922ac3c40e81dd9\", \"got_v8_revision\": \"b072390cd31323aec324401a835bc319b94878d4\", \"got_v8_revision_cp\": \"refs/heads/8.5.29@{#1}\", \"got_webrtc_revision\": \"2671dac29c27d033d14a79708e9a2b9b2b66b8d1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31327}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.android.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7443f460587f22e09d0b5e154f35af1cb388bdaf\", \"swarm_hashes\": {\"android_browsertests\": \"16490115e30fe4ae120305d0c0cd023d54bb753a\", \"chrome_public_test_apk\": \"549620d95f0d07aae2ee0529b263863f54b65e7b\", \"content_browsertests\": \"f1fe173adfb50471d11444596a9f36968e324c81\", \"content_shell_test_apk\": \"820eaf708545eb81f80e4563b10eec5883ca129f\", \"content_unittests\": \"cd4c57db315f72176dcb069eea278a3dad9f2eeb\", \"unit_tests\": \"95a4762dbbe628692fe057b2ab3acb571946181a\"}, \"swarm_hashes_refs/heads/master(at){#770331}_without_patch\": {\"android_browsertests\": \"16490115e30fe4ae120305d0c0cd023d54bb753a\", \"chrome_public_test_apk\": \"549620d95f0d07aae2ee0529b263863f54b65e7b\", \"content_browsertests\": \"f1fe173adfb50471d11444596a9f36968e324c81\", \"content_shell_test_apk\": \"820eaf708545eb81f80e4563b10eec5883ca129f\", \"content_unittests\": \"cd4c57db315f72176dcb069eea278a3dad9f2eeb\", \"unit_tests\": \"95a4762dbbe628692fe057b2ab3acb571946181a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0b7b4a76c348e02b1b0360cbf12cf6c81ca8949f924020091b0ce0558154e869_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"builder_fbb3d50a702ddd064319c35d96f4b89f6e7a29ba4c11145a49be75e11fea82c5_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-82-1pjt\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"3 Test Suite(s) failed.\\n\\n**bf_cache_content_browsertests on Android device Nexus 5** failed because of:\\n\\n- RenderDocumentHostUserDataTest.CrossSiteNavigation\\n\\n- SitePerProcessBrowserTest.NavigateMainFrameToChildSite\\n\\n- SitePerProcessBrowserTest.ActiveSandboxFlagsRetainedAfterUnload\\n\\n- RenderDocumentHostUserDataTest.FailedNavigation\\n\\n- ...24 more failure(s) (28 total)...\\n\\n**bf_cache_chrome_public_test_apk on Android device Nexus 5** failed because of:\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testSearchInIncognitoSingleSurface__Instant_Return\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testScroll_OmniboxOnly_Top__NoInstant_NoReturn\\n\\n- org.chromium.chrome.features.start_surface.StartSurfaceTest#testShow_SingleAsTabSwitcher__Instant_NoReturn\\n\\n- ...129 more failure(s) (132 total)...\\n\\n#### ...1 more test(s)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1589928250678505",
"status": "COMPLETED",
"status_changed_ts": "1589928251614356",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/2815",
"builder:android-bfcache-rel",
"buildset:commit/git/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7443f460587f22e09d0b5e154f35af1cb388bdaf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045264000149523216",
"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/8879833937399583728/+/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:4c47e1696cae3710",
"user_agent:luci-scheduler"
],
"updated_ts": "1589933976825891",
"url": "https://ci.chromium.org/b/8879833937399583728",
"utcnow_ts": "1589933977312132"
}
}
[00:19:37.325] Invocation finished in 1h48m37.173557391s with status FAILED