[08:17:18.512] New invocation is queued and will start shortly
[08:17:19.966] Starting the invocation (attempt 1)
[08:17:20.015] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:17:20.015] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:17:20.015] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9044955322082979008",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9044955322082979008",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"gitiles_ref:refs/heads/master"
]
}
[08:17:21.128] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1590221840164752",
"id": "8879525259686173568",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590221840891636",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17303",
"builder:Linux CFI",
"buildset:commit/git/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044955322082979008",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879525259686173568/+/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": "1590221840891781",
"url": "https://ci.chromium.org/b/8879525259686173568",
"utcnow_ts": "1590221841118231"
}
}
[08:17:21.128] Task URL: https://ci.chromium.org/b/8879525259686173568
[08:17:21.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:2:0) after 1m0s
[08:18:21.159] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:2:0)
[08:18:21.159] Timer tick, asking Buildbucket for the build status
[08:18:21.410] Build 8879525259686173568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:21.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:3:0) after 1m0s
[08:19:21.438] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:3:0)
[08:19:21.438] Timer tick, asking Buildbucket for the build status
[08:19:21.759] Build 8879525259686173568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:21.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:4:0) after 1m0s
[08:19:58.646] Received PubSub notification, asking Buildbucket for the build status
[08:19:58.739] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:21.781] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:4:0)
[08:20:21.781] Timer tick, asking Buildbucket for the build status
[08:20:21.993] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:21.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:6:0) after 1m0s
[08:21:22.066] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:6:0)
[08:21:22.066] Timer tick, asking Buildbucket for the build status
[08:21:22.248] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:22.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:7:0) after 1m0s
[08:22:22.323] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:7:0)
[08:22:22.323] Timer tick, asking Buildbucket for the build status
[08:22:22.673] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:22.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:8:0) after 1m0s
[08:23:22.712] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:8:0)
[08:23:22.712] Timer tick, asking Buildbucket for the build status
[08:23:22.825] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:22.826] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:9:0) after 1m0s
[08:24:22.869] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:9:0)
[08:24:22.869] Timer tick, asking Buildbucket for the build status
[08:24:23.032] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:23.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:10:0) after 1m0s
[08:25:23.112] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:10:0)
[08:25:23.112] Timer tick, asking Buildbucket for the build status
[08:25:23.251] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:23.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:11:0) after 1m0s
[08:26:23.278] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:11:0)
[08:26:23.278] Timer tick, asking Buildbucket for the build status
[08:26:23.486] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:23.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:12:0) after 1m0s
[08:27:23.520] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:12:0)
[08:27:23.520] Timer tick, asking Buildbucket for the build status
[08:27:23.635] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:23.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:13:0) after 1m0s
[08:28:23.657] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:13:0)
[08:28:23.657] Timer tick, asking Buildbucket for the build status
[08:28:23.986] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:23.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:14:0) after 1m0s
[08:29:24.073] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:14:0)
[08:29:24.073] Timer tick, asking Buildbucket for the build status
[08:29:24.921] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:24.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:15:0) after 1m0s
[08:30:24.939] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:15:0)
[08:30:24.939] Timer tick, asking Buildbucket for the build status
[08:30:25.235] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:25.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:16:0) after 1m0s
[08:31:25.290] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:16:0)
[08:31:25.290] Timer tick, asking Buildbucket for the build status
[08:31:25.488] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:25.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:17:0) after 1m0s
[08:32:25.616] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:17:0)
[08:32:25.616] Timer tick, asking Buildbucket for the build status
[08:32:25.820] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:25.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:18:0) after 1m0s
[08:33:25.842] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:18:0)
[08:33:25.842] Timer tick, asking Buildbucket for the build status
[08:33:25.976] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:25.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:19:0) after 1m0s
[08:34:26.227] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:19:0)
[08:34:26.227] Timer tick, asking Buildbucket for the build status
[08:34:26.512] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:26.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:20:0) after 1m0s
[08:35:26.689] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:20:0)
[08:35:26.689] Timer tick, asking Buildbucket for the build status
[08:35:26.852] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:26.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:21:0) after 1m0s
[08:36:26.985] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:21:0)
[08:36:26.985] Timer tick, asking Buildbucket for the build status
[08:36:27.132] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:27.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:22:0) after 1m0s
[08:37:27.182] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:22:0)
[08:37:27.182] Timer tick, asking Buildbucket for the build status
[08:37:27.555] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:27.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:23:0) after 1m0s
[08:38:27.656] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:23:0)
[08:38:27.656] Timer tick, asking Buildbucket for the build status
[08:38:27.824] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:27.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:24:0) after 1m0s
[08:39:27.882] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:24:0)
[08:39:27.882] Timer tick, asking Buildbucket for the build status
[08:39:28.116] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:28.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:25:0) after 1m0s
[08:40:28.096] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:25:0)
[08:40:28.096] Timer tick, asking Buildbucket for the build status
[08:40:28.347] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:26:0) after 1m0s
[08:41:28.380] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:26:0)
[08:41:28.380] Timer tick, asking Buildbucket for the build status
[08:41:28.567] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:28.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:27:0) after 1m0s
[08:42:28.589] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:27:0)
[08:42:28.589] Timer tick, asking Buildbucket for the build status
[08:42:28.760] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:28.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:28:0) after 1m0s
[08:43:28.834] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:28:0)
[08:43:28.834] Timer tick, asking Buildbucket for the build status
[08:43:29.049] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:29.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:29:0) after 1m0s
[08:44:29.122] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:29:0)
[08:44:29.122] Timer tick, asking Buildbucket for the build status
[08:44:29.402] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:29.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:30:0) after 1m0s
[08:45:29.448] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:30:0)
[08:45:29.448] Timer tick, asking Buildbucket for the build status
[08:45:29.618] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:29.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:31:0) after 1m0s
[08:46:29.698] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:31:0)
[08:46:29.698] Timer tick, asking Buildbucket for the build status
[08:46:29.961] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:29.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:32:0) after 1m0s
[08:47:30.011] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:32:0)
[08:47:30.011] Timer tick, asking Buildbucket for the build status
[08:47:30.261] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:30.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:33:0) after 1m0s
[08:48:30.287] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:33:0)
[08:48:30.287] Timer tick, asking Buildbucket for the build status
[08:48:30.429] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:30.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:34:0) after 1m0s
[08:49:30.601] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:34:0)
[08:49:30.601] Timer tick, asking Buildbucket for the build status
[08:49:30.739] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:30.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:35:0) after 1m0s
[08:50:30.874] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:35:0)
[08:50:30.874] Timer tick, asking Buildbucket for the build status
[08:50:31.065] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:31.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:36:0) after 1m0s
[08:51:31.085] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:36:0)
[08:51:31.085] Timer tick, asking Buildbucket for the build status
[08:51:31.303] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:31.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:37:0) after 1m0s
[08:52:31.327] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:37:0)
[08:52:31.327] Timer tick, asking Buildbucket for the build status
[08:52:31.565] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:31.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:38:0) after 1m0s
[08:53:31.745] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:38:0)
[08:53:31.745] Timer tick, asking Buildbucket for the build status
[08:53:31.984] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:31.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:39:0) after 1m0s
[08:54:32.031] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:39:0)
[08:54:32.031] Timer tick, asking Buildbucket for the build status
[08:54:32.386] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:32.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:40:0) after 1m0s
[08:55:32.426] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:40:0)
[08:55:32.426] Timer tick, asking Buildbucket for the build status
[08:55:33.170] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:33.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:41:0) after 1m0s
[08:56:33.190] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:41:0)
[08:56:33.190] Timer tick, asking Buildbucket for the build status
[08:56:33.939] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:33.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:42:0) after 1m0s
[08:57:34.063] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:42:0)
[08:57:34.063] Timer tick, asking Buildbucket for the build status
[08:57:34.584] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:34.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:43:0) after 1m0s
[08:58:34.618] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:43:0)
[08:58:34.618] Timer tick, asking Buildbucket for the build status
[08:58:34.961] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:34.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:44:0) after 1m0s
[08:59:35.015] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:44:0)
[08:59:35.015] Timer tick, asking Buildbucket for the build status
[08:59:35.134] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:35.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:45:0) after 1m0s
[09:00:35.176] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:45:0)
[09:00:35.176] Timer tick, asking Buildbucket for the build status
[09:00:36.172] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:36.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:46:0) after 1m0s
[09:01:36.242] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:46:0)
[09:01:36.242] Timer tick, asking Buildbucket for the build status
[09:01:36.664] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:36.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:47:0) after 1m0s
[09:02:36.684] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:47:0)
[09:02:36.684] Timer tick, asking Buildbucket for the build status
[09:02:36.956] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:36.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:48:0) after 1m0s
[09:03:37.049] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:48:0)
[09:03:37.049] Timer tick, asking Buildbucket for the build status
[09:03:37.189] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:37.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:49:0) after 1m0s
[09:04:37.250] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:49:0)
[09:04:37.250] Timer tick, asking Buildbucket for the build status
[09:04:37.415] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:37.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:50:0) after 1m0s
[09:05:37.439] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:50:0)
[09:05:37.439] Timer tick, asking Buildbucket for the build status
[09:05:37.667] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:37.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:51:0) after 1m0s
[09:06:37.695] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:51:0)
[09:06:37.695] Timer tick, asking Buildbucket for the build status
[09:06:37.842] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:37.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:52:0) after 1m0s
[09:07:37.881] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:52:0)
[09:07:37.881] Timer tick, asking Buildbucket for the build status
[09:07:38.005] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:38.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:53:0) after 1m0s
[09:08:38.030] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:53:0)
[09:08:38.030] Timer tick, asking Buildbucket for the build status
[09:08:38.223] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:38.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:54:0) after 1m0s
[09:09:38.766] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:54:0)
[09:09:38.766] Timer tick, asking Buildbucket for the build status
[09:09:38.897] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:38.897] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:55:0) after 1m0s
[09:10:38.917] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:55:0)
[09:10:38.917] Timer tick, asking Buildbucket for the build status
[09:10:39.234] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:39.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:56:0) after 1m0s
[09:11:39.264] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:56:0)
[09:11:39.264] Timer tick, asking Buildbucket for the build status
[09:11:39.490] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:57:0) after 1m0s
[09:12:39.492] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:57:0)
[09:12:39.492] Timer tick, asking Buildbucket for the build status
[09:12:39.947] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:39.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:58:0) after 1m0s
[09:13:40.086] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:58:0)
[09:13:40.086] Timer tick, asking Buildbucket for the build status
[09:13:40.547] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:40.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:59:0) after 1m0s
[09:14:40.582] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:59:0)
[09:14:40.582] Timer tick, asking Buildbucket for the build status
[09:14:40.823] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:40.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:60:0) after 1m0s
[09:15:40.899] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:60:0)
[09:15:40.899] Timer tick, asking Buildbucket for the build status
[09:15:41.353] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:41.353] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:61:0) after 1m0s
[09:16:41.379] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:61:0)
[09:16:41.379] Timer tick, asking Buildbucket for the build status
[09:16:41.651] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:41.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:62:0) after 1m0s
[09:17:41.663] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:62:0)
[09:17:41.663] Timer tick, asking Buildbucket for the build status
[09:17:41.759] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:41.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:63:0) after 1m0s
[09:18:41.786] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:63:0)
[09:18:41.786] Timer tick, asking Buildbucket for the build status
[09:18:42.259] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:42.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:64:0) after 1m0s
[09:19:42.310] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:64:0)
[09:19:42.310] Timer tick, asking Buildbucket for the build status
[09:19:42.463] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:42.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:65:0) after 1m0s
[09:20:42.535] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:65:0)
[09:20:42.535] Timer tick, asking Buildbucket for the build status
[09:20:42.739] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:42.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:66:0) after 1m0s
[09:21:42.781] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:66:0)
[09:21:42.781] Timer tick, asking Buildbucket for the build status
[09:21:42.945] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:42.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:67:0) after 1m0s
[09:22:42.955] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:67:0)
[09:22:42.955] Timer tick, asking Buildbucket for the build status
[09:22:43.144] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:43.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:68:0) after 1m0s
[09:23:43.203] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:68:0)
[09:23:43.203] Timer tick, asking Buildbucket for the build status
[09:23:43.552] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:43.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:69:0) after 1m0s
[09:24:43.588] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:69:0)
[09:24:43.588] Timer tick, asking Buildbucket for the build status
[09:24:43.913] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:43.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:70:0) after 1m0s
[09:25:43.962] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:70:0)
[09:25:43.962] Timer tick, asking Buildbucket for the build status
[09:25:44.360] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:44.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:71:0) after 1m0s
[09:26:44.438] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:71:0)
[09:26:44.438] Timer tick, asking Buildbucket for the build status
[09:26:44.783] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:44.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:72:0) after 1m0s
[09:27:44.805] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:72:0)
[09:27:44.805] Timer tick, asking Buildbucket for the build status
[09:27:44.924] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:44.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:73:0) after 1m0s
[09:28:44.983] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:73:0)
[09:28:44.983] Timer tick, asking Buildbucket for the build status
[09:28:45.119] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:45.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:74:0) after 1m0s
[09:29:45.212] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:74:0)
[09:29:45.212] Timer tick, asking Buildbucket for the build status
[09:29:45.538] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:75:0) after 1m0s
[09:30:45.639] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:75:0)
[09:30:45.639] Timer tick, asking Buildbucket for the build status
[09:30:45.812] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:45.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:76:0) after 1m0s
[09:31:45.929] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:76:0)
[09:31:45.929] Timer tick, asking Buildbucket for the build status
[09:31:46.380] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:46.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:77:0) after 1m0s
[09:32:46.485] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:77:0)
[09:32:46.485] Timer tick, asking Buildbucket for the build status
[09:32:46.654] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:46.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:78:0) after 1m0s
[09:33:46.728] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:78:0)
[09:33:46.728] Timer tick, asking Buildbucket for the build status
[09:33:46.816] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:46.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:79:0) after 1m0s
[09:34:46.889] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:79:0)
[09:34:46.889] Timer tick, asking Buildbucket for the build status
[09:34:47.042] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:47.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:80:0) after 1m0s
[09:35:47.164] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:80:0)
[09:35:47.164] Timer tick, asking Buildbucket for the build status
[09:35:47.487] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:47.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:81:0) after 1m0s
[09:36:47.567] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:81:0)
[09:36:47.567] Timer tick, asking Buildbucket for the build status
[09:36:48.350] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:48.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:82:0) after 1m0s
[09:37:48.380] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:82:0)
[09:37:48.380] Timer tick, asking Buildbucket for the build status
[09:37:48.505] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:48.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:83:0) after 1m0s
[09:38:48.531] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:83:0)
[09:38:48.531] Timer tick, asking Buildbucket for the build status
[09:38:48.896] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:48.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:84:0) after 1m0s
[09:39:48.939] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:84:0)
[09:39:48.939] Timer tick, asking Buildbucket for the build status
[09:39:49.158] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:49.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:85:0) after 1m0s
[09:40:49.187] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:85:0)
[09:40:49.187] Timer tick, asking Buildbucket for the build status
[09:40:49.424] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:49.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:86:0) after 1m0s
[09:41:49.450] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:86:0)
[09:41:49.450] Timer tick, asking Buildbucket for the build status
[09:41:49.561] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:49.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:87:0) after 1m0s
[09:42:49.585] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:87:0)
[09:42:49.585] Timer tick, asking Buildbucket for the build status
[09:42:49.772] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:49.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:88:0) after 1m0s
[09:43:49.839] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:88:0)
[09:43:49.839] Timer tick, asking Buildbucket for the build status
[09:43:50.015] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:89:0) after 1m0s
[09:44:50.051] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:89:0)
[09:44:50.051] Timer tick, asking Buildbucket for the build status
[09:44:51.760] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:51.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:90:0) after 1m0s
[09:45:51.790] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:90:0)
[09:45:51.790] Timer tick, asking Buildbucket for the build status
[09:45:51.944] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:51.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:91:0) after 1m0s
[09:46:51.968] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:91:0)
[09:46:51.968] Timer tick, asking Buildbucket for the build status
[09:46:52.158] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:52.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:92:0) after 1m0s
[09:47:52.179] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:92:0)
[09:47:52.179] Timer tick, asking Buildbucket for the build status
[09:47:52.372] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:52.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:93:0) after 1m0s
[09:48:52.582] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:93:0)
[09:48:52.582] Timer tick, asking Buildbucket for the build status
[09:48:52.691] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:52.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:94:0) after 1m0s
[09:49:52.677] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:94:0)
[09:49:52.677] Timer tick, asking Buildbucket for the build status
[09:49:52.791] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:52.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:95:0) after 1m0s
[09:50:52.816] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:95:0)
[09:50:52.816] Timer tick, asking Buildbucket for the build status
[09:50:52.984] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:52.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:96:0) after 1m0s
[09:51:53.006] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:96:0)
[09:51:53.006] Timer tick, asking Buildbucket for the build status
[09:51:53.226] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:53.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:97:0) after 1m0s
[09:52:53.300] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:97:0)
[09:52:53.300] Timer tick, asking Buildbucket for the build status
[09:52:53.874] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:53.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:98:0) after 1m0s
[09:53:53.976] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:98:0)
[09:53:53.976] Timer tick, asking Buildbucket for the build status
[09:53:54.565] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:54.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:99:0) after 1m0s
[09:54:54.592] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:99:0)
[09:54:54.592] Timer tick, asking Buildbucket for the build status
[09:54:54.727] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:54.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:100:0) after 1m0s
[09:55:54.749] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:100:0)
[09:55:54.749] Timer tick, asking Buildbucket for the build status
[09:55:54.859] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:54.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:101:0) after 1m0s
[09:56:54.891] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:101:0)
[09:56:54.891] Timer tick, asking Buildbucket for the build status
[09:56:55.083] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:55.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:102:0) after 1m0s
[09:57:55.116] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:102:0)
[09:57:55.116] Timer tick, asking Buildbucket for the build status
[09:57:55.287] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:55.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:103:0) after 1m0s
[09:58:55.377] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:103:0)
[09:58:55.377] Timer tick, asking Buildbucket for the build status
[09:58:55.516] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:55.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:104:0) after 1m0s
[09:59:55.543] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:104:0)
[09:59:55.543] Timer tick, asking Buildbucket for the build status
[09:59:55.773] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:55.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:105:0) after 1m0s
[10:00:55.814] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:105:0)
[10:00:55.814] Timer tick, asking Buildbucket for the build status
[10:00:58.011] Build 8879525259686173568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:58.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044955322082979008:106:0) after 1m0s
[10:01:12.125] Received PubSub notification, asking Buildbucket for the build status
[10:01:12.489] Build 8879525259686173568: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:01:12.489] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1590228069937984",
"created_by": "project:chromium",
"created_ts": "1590221840164752",
"failure_reason": "BUILD_FAILURE",
"id": "8879525259686173568",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DiceBrowserTest.EnableSyncAfterToken\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.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-32-19-uv3s\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux CFI\", \"buildnumber\": 17303, \"got_angle_revision\": \"d3d7b95bac1957900d346637600adc9eacf1fd64\", \"got_dawn_revision\": \"65ee6497d620110546181c1dfbd0cc3064f9f7f9\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060\", \"got_revision_cp\": \"refs/heads/master@{#771382}\", \"got_swarming_client_revision\": \"7e8636295f4763a5f5aace1e700b17a4af3c81b7\", \"got_swiftshader_revision\": \"cc5cda0f997d8280712920f2749bbdfe7a0f4dc3\", \"got_v8_revision\": \"414d114aaaad4f0cac357eb2705a71e3ca7bf898\", \"got_v8_revision_cp\": \"refs/heads/8.5.34@{#1}\", \"got_webrtc_revision\": \"3cc1a6509bf6143f9d52d76a7648a9c8d3bdccea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31336}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.memory\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060\", \"swarm_hashes\": {\"accessibility_unittests\": \"072f4cd0595e131453d4ad0f35017f0331192428\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"fbc0318a3fc84b6a8df2581dc369b132fdda8603\", \"aura_unittests\": \"8d30e88ab380962c1daeab783d8f3fcb5e12b47d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"1463d372c9bc375a9e5a44215057dd61be7b5065\", \"blink_fuzzer_unittests\": \"53da1ff7d712a9c90d14eb4b4caa6d6283d9a41b\", \"blink_heap_unittests\": \"3ffd7b4860c04d9f5dc5d2c3e45dc07a579acf8d\", \"blink_platform_unittests\": \"efe2c4a2420f12a1ea6932205c159c63ac4624a8\", \"blink_unittests\": \"95d490550b38b938b13358044b921fb73538a0a4\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"0765b4505151c768ba41ad8a4ba1ed8947580af4\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"bfac38e6e55bf1d68cc80744e86d2e9377257bc2\", \"cast_unittests\": \"b6290331bd07cfc9262979a926790553b5672269\", \"cc_unittests\": \"67c3a2ce7198358e5922ca34f709d96d0d730152\", \"chrome_app_unittests\": \"444439d70417ad19bfe7b8a7302d312945964d79\", \"chromedriver_unittests\": \"05e656db5a58b5d7304c6e6d03a601413974bcfe\", \"components_browsertests\": \"3281a315478c8f131d3865fa2df984b48dcca3f9\", \"components_unittests\": \"1b733dbccfcde6a9449c8f7297095dfb8a269c24\", \"compositor_unittests\": \"7afaf9f0d9856852e12fe5d975658565e67be765\", \"content_browsertests\": \"c670eb43f21521b0679e91d303cd006930fbc9a8\", \"content_unittests\": \"f5209796a4e376c9be81a78028b2527c2ee1d3a4\", \"cronet_tests\": \"bef94b584b55663475741f78bd50c80eb0a69055\", \"cronet_unittests\": \"10240728dac39455d99908cebcbc66189bac3e3d\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"6e4cb66761518872b9468285f67917009c31a35b\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"1c31e6516118edb25981b75a11b88f25083db6dd\", \"extensions_browsertests\": \"e1afe48785b161fddb5aab718b0702ebceb9fdde\", \"extensions_unittests\": \"cce29b2e367c1208156b825866aaa0a0e185667d\", \"filesystem_service_unittests\": \"8dc33a2b98891d0f790ab99c0131ff0de22b0f98\", \"gcm_unit_tests\": \"98c769f051250c43e13e81cfc48d0655ad9ff391\", \"gfx_unittests\": \"e0c0d78424c6bcdf721fc0021f26d83bdcde51e0\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"11704c31120982e14c49bdad559921173435296d\", \"gl_unittests\": \"f6b2edd0cdf8523c965d15b5709bf29e06140e4b\", \"google_apis_unittests\": \"5c5a3529bbc0d3f354009fa49247e378e7634c80\", \"gpu_unittests\": \"69b4f8b9d61a0b103331fc2570ff421b57022a60\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"b4d45b75b627391826cea51b8ebfd0c1b74c86c8\", \"headless_unittests\": \"377904192e44a559ca6370426670b90bf266e596\", \"interactive_ui_tests\": \"7f26e041029f3d9df85798684356ca696b4b91c0\", \"ipc_tests\": \"1f86306768b710d31a7cb4c46bad46a401510a04\", \"jingle_unittests\": \"96c6c3507d86fd51ea1cb3e28f0c9ad81890387b\", \"latency_unittests\": \"6ca05581c0c92fd6e165fc9d92f3242e12ca2125\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"3ecde1e5624235b81f4c22fa5c747a8e11ca0a5f\", \"media_unittests\": \"328e3b24b1847daee409e3e824357908ac176a58\", \"message_center_unittests\": \"15552943d9c46aef51248077b84c8923f0ae55f3\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"d6b9aba3ce498464d1cf176bf90ea09169a0931a\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"673c7c2d8587663cf885b896aaa01f627ecec5ee\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"8f473822e0062a75594ed1f4bbfeadf70bc96a97\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"3e62fc251d0d87f9bd2c5643f5943239eecc6d46\", \"printing_unittests\": \"fd6738efbc71921103c01fbcf60ff36706f4de70\", \"remoting_unittests\": \"b8b7677816d0472918937717a82e2d421fd483b7\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"081ef688565abf8cebdf989e83e03216e9fe06a8\", \"services_unittests\": \"7532721ff95128289279e8c3494eb5795182c5c0\", \"shell_dialogs_unittests\": \"8e6087711df04722fd596165094258b8343c1202\", \"skia_unittests\": \"ffc0d94d94f9354bfea6f354921631714ae0c1b5\", \"snapshot_unittests\": \"245c9893cd3aff4e3f3d9b0ce0a30c987a3b1bd2\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"9ff401a32de6d1151fd524822e534d77505d8282\", \"sync_integration_tests\": \"7390f3de4c193561347f47f1373af72948438cf2\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"2574e3838fe1413a3d0a0b40afeb52a899e7948a\", \"ui_touch_selection_unittests\": \"9c926be948dcd48c8bdb1fa7d122404ec22aa769\", \"unit_tests\": \"801acb40e08e0b978b7941bc98267cead1f046a8\", \"url_unittests\": \"61158c0f391cd81d2e9bf6a035de3b6ca454f5aa\", \"views_unittests\": \"2f99184d5734582125354bbd410fc8ce466586a6\", \"viz_unittests\": \"ace9179fd4b8b64beb8d884607e4f8153c4f093f\", \"vr_common_unittests\": \"4e1e12b92a11cd15c80be3673ba90bc6a34d9075\", \"vr_pixeltests\": \"9a18d6648bce60e5e596aa4bbac93430fc9c2506\", \"wm_unittests\": \"3b7a949d0c5110588763f7ed35b95aba78bbec67\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}, \"swarm_hashes_refs/heads/master(at){#771382}_without_patch\": {\"accessibility_unittests\": \"072f4cd0595e131453d4ad0f35017f0331192428\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"fbc0318a3fc84b6a8df2581dc369b132fdda8603\", \"aura_unittests\": \"8d30e88ab380962c1daeab783d8f3fcb5e12b47d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"1463d372c9bc375a9e5a44215057dd61be7b5065\", \"blink_fuzzer_unittests\": \"53da1ff7d712a9c90d14eb4b4caa6d6283d9a41b\", \"blink_heap_unittests\": \"3ffd7b4860c04d9f5dc5d2c3e45dc07a579acf8d\", \"blink_platform_unittests\": \"efe2c4a2420f12a1ea6932205c159c63ac4624a8\", \"blink_unittests\": \"95d490550b38b938b13358044b921fb73538a0a4\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"0765b4505151c768ba41ad8a4ba1ed8947580af4\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"bfac38e6e55bf1d68cc80744e86d2e9377257bc2\", \"cast_unittests\": \"b6290331bd07cfc9262979a926790553b5672269\", \"cc_unittests\": \"67c3a2ce7198358e5922ca34f709d96d0d730152\", \"chrome_app_unittests\": \"444439d70417ad19bfe7b8a7302d312945964d79\", \"chromedriver_unittests\": \"05e656db5a58b5d7304c6e6d03a601413974bcfe\", \"components_browsertests\": \"3281a315478c8f131d3865fa2df984b48dcca3f9\", \"components_unittests\": \"1b733dbccfcde6a9449c8f7297095dfb8a269c24\", \"compositor_unittests\": \"7afaf9f0d9856852e12fe5d975658565e67be765\", \"content_browsertests\": \"c670eb43f21521b0679e91d303cd006930fbc9a8\", \"content_unittests\": \"f5209796a4e376c9be81a78028b2527c2ee1d3a4\", \"cronet_tests\": \"bef94b584b55663475741f78bd50c80eb0a69055\", \"cronet_unittests\": \"10240728dac39455d99908cebcbc66189bac3e3d\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"6e4cb66761518872b9468285f67917009c31a35b\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"1c31e6516118edb25981b75a11b88f25083db6dd\", \"extensions_browsertests\": \"e1afe48785b161fddb5aab718b0702ebceb9fdde\", \"extensions_unittests\": \"cce29b2e367c1208156b825866aaa0a0e185667d\", \"filesystem_service_unittests\": \"8dc33a2b98891d0f790ab99c0131ff0de22b0f98\", \"gcm_unit_tests\": \"98c769f051250c43e13e81cfc48d0655ad9ff391\", \"gfx_unittests\": \"e0c0d78424c6bcdf721fc0021f26d83bdcde51e0\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"11704c31120982e14c49bdad559921173435296d\", \"gl_unittests\": \"f6b2edd0cdf8523c965d15b5709bf29e06140e4b\", \"google_apis_unittests\": \"5c5a3529bbc0d3f354009fa49247e378e7634c80\", \"gpu_unittests\": \"69b4f8b9d61a0b103331fc2570ff421b57022a60\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"b4d45b75b627391826cea51b8ebfd0c1b74c86c8\", \"headless_unittests\": \"377904192e44a559ca6370426670b90bf266e596\", \"interactive_ui_tests\": \"7f26e041029f3d9df85798684356ca696b4b91c0\", \"ipc_tests\": \"1f86306768b710d31a7cb4c46bad46a401510a04\", \"jingle_unittests\": \"96c6c3507d86fd51ea1cb3e28f0c9ad81890387b\", \"latency_unittests\": \"6ca05581c0c92fd6e165fc9d92f3242e12ca2125\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"3ecde1e5624235b81f4c22fa5c747a8e11ca0a5f\", \"media_unittests\": \"328e3b24b1847daee409e3e824357908ac176a58\", \"message_center_unittests\": \"15552943d9c46aef51248077b84c8923f0ae55f3\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"d6b9aba3ce498464d1cf176bf90ea09169a0931a\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"673c7c2d8587663cf885b896aaa01f627ecec5ee\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"8f473822e0062a75594ed1f4bbfeadf70bc96a97\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"3e62fc251d0d87f9bd2c5643f5943239eecc6d46\", \"printing_unittests\": \"fd6738efbc71921103c01fbcf60ff36706f4de70\", \"remoting_unittests\": \"b8b7677816d0472918937717a82e2d421fd483b7\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"081ef688565abf8cebdf989e83e03216e9fe06a8\", \"services_unittests\": \"7532721ff95128289279e8c3494eb5795182c5c0\", \"shell_dialogs_unittests\": \"8e6087711df04722fd596165094258b8343c1202\", \"skia_unittests\": \"ffc0d94d94f9354bfea6f354921631714ae0c1b5\", \"snapshot_unittests\": \"245c9893cd3aff4e3f3d9b0ce0a30c987a3b1bd2\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"9ff401a32de6d1151fd524822e534d77505d8282\", \"sync_integration_tests\": \"7390f3de4c193561347f47f1373af72948438cf2\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"2574e3838fe1413a3d0a0b40afeb52a899e7948a\", \"ui_touch_selection_unittests\": \"9c926be948dcd48c8bdb1fa7d122404ec22aa769\", \"unit_tests\": \"801acb40e08e0b978b7941bc98267cead1f046a8\", \"url_unittests\": \"61158c0f391cd81d2e9bf6a035de3b6ca454f5aa\", \"views_unittests\": \"2f99184d5734582125354bbd410fc8ce466586a6\", \"viz_unittests\": \"ace9179fd4b8b64beb8d884607e4f8153c4f093f\", \"vr_common_unittests\": \"4e1e12b92a11cd15c80be3673ba90bc6a34d9075\", \"vr_pixeltests\": \"9a18d6648bce60e5e596aa4bbac93430fc9c2506\", \"wm_unittests\": \"3b7a949d0c5110588763f7ed35b95aba78bbec67\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0264569d20b29e1d5e6df044c8d1223ed84f9f22180c83dac41e8db0ff2b71db_v2\", \"builder_4546ef179f50eb727f19fe6abd7705a7b8921ff8263e4e2e8e2644dd615ef1a7_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-19-uv3s\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DiceBrowserTest.EnableSyncAfterToken\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1590221996633889",
"status": "COMPLETED",
"status_changed_ts": "1590221998085308",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17303",
"builder:Linux CFI",
"buildset:commit/git/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a10dc17d71d3ae4087a9ccb23c1de9dc99b3060",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044955322082979008",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879525259686173568/+/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:4c596d2d8ea3ac10",
"user_agent:luci-scheduler"
],
"updated_ts": "1590228070923054",
"url": "https://ci.chromium.org/b/8879525259686173568",
"utcnow_ts": "1590228072449488"
}
}
[10:01:12.489] Invocation finished in 1h43m53.993432539s with status FAILED