[16:46:52.254] New invocation is queued and will start shortly
[16:46:53.502] Starting the invocation (attempt 1)
[16:46:53.570] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:46:53.570] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:46:53.570] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077538171096773424",
"parameters_json": "{\"builder_name\":\"CFI Linux ToT\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"53855e57317e88d5ae19ac9964ffa17a4c965c49\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:CFI Linux ToT",
"scheduler_invocation_id:9077538171096773424",
"scheduler_job_id:chromium/CFI Linux ToT",
"user_agent:luci-scheduler",
"buildset:commit/git/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"gitiles_ref:refs/heads/master"
]
}
[16:46:54.998] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559148414254747",
"id": "8912108108332981872",
"parameters_json": "{\"builder_name\": \"CFI Linux ToT\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53855e57317e88d5ae19ac9964ffa17a4c965c49\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559148414846691",
"tags": [
"build_address:luci.chromium.ci/CFI Linux ToT/6889",
"builder:CFI Linux ToT",
"buildset:commit/git/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077538171096773424",
"scheduler_job_id:chromium/CFI Linux ToT",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912108108332981872/+/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": "1559148414846779",
"url": "https://ci.chromium.org/b/8912108108332981872",
"utcnow_ts": "1559148414988741"
}
}
[16:46:54.998] Task URL: https://ci.chromium.org/b/8912108108332981872
[16:46:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:2:0) after 1m0s
[16:47:05.730] Received PubSub notification, asking Buildbucket for the build status
[16:47:05.905] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:55.055] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:2:0)
[16:47:55.055] Timer tick, asking Buildbucket for the build status
[16:47:55.324] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:55.324] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:4:0) after 1m0s
[16:48:55.379] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:4:0)
[16:48:55.380] Timer tick, asking Buildbucket for the build status
[16:48:55.755] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:55.755] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:5:0) after 1m0s
[16:49:55.918] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:5:0)
[16:49:55.918] Timer tick, asking Buildbucket for the build status
[16:49:57.943] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:57.943] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:6:0) after 1m0s
[16:50:58.053] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:6:0)
[16:50:58.053] Timer tick, asking Buildbucket for the build status
[16:50:59.428] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:59.428] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:7:0) after 1m0s
[16:51:59.490] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:7:0)
[16:51:59.490] Timer tick, asking Buildbucket for the build status
[16:52:02.243] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:02.243] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:8:0) after 1m0s
[16:53:02.273] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:8:0)
[16:53:02.273] Timer tick, asking Buildbucket for the build status
[16:53:03.930] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:03.930] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:9:0) after 1m0s
[16:54:03.983] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:9:0)
[16:54:03.983] Timer tick, asking Buildbucket for the build status
[16:54:05.835] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:05.835] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:10:0) after 1m0s
[16:55:05.903] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:10:0)
[16:55:05.903] Timer tick, asking Buildbucket for the build status
[16:55:06.539] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:06.539] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:11:0) after 1m0s
[16:56:06.595] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:11:0)
[16:56:06.595] Timer tick, asking Buildbucket for the build status
[16:56:06.827] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:06.827] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:12:0) after 1m0s
[16:57:06.969] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:12:0)
[16:57:06.969] Timer tick, asking Buildbucket for the build status
[16:57:09.684] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:09.684] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:13:0) after 1m0s
[16:58:09.759] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:13:0)
[16:58:09.759] Timer tick, asking Buildbucket for the build status
[16:58:17.188] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:17.188] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:14:0) after 1m0s
[16:59:17.217] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:14:0)
[16:59:17.217] Timer tick, asking Buildbucket for the build status
[16:59:18.751] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:18.751] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:15:0) after 1m0s
[17:00:18.765] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:15:0)
[17:00:18.765] Timer tick, asking Buildbucket for the build status
[17:00:22.129] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:22.129] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:16:0) after 1m0s
[17:01:22.149] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:16:0)
[17:01:22.149] Timer tick, asking Buildbucket for the build status
[17:01:22.276] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:22.276] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:17:0) after 1m0s
[17:02:22.296] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:17:0)
[17:02:22.296] Timer tick, asking Buildbucket for the build status
[17:02:23.659] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:23.659] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:18:0) after 1m0s
[17:03:23.685] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:18:0)
[17:03:23.685] Timer tick, asking Buildbucket for the build status
[17:03:23.901] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:23.901] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:19:0) after 1m0s
[17:04:23.896] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:19:0)
[17:04:23.896] Timer tick, asking Buildbucket for the build status
[17:04:24.899] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:24.899] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:20:0) after 1m0s
[17:05:24.932] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:20:0)
[17:05:24.932] Timer tick, asking Buildbucket for the build status
[17:05:27.074] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:27.074] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:21:0) after 1m0s
[17:06:27.117] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:21:0)
[17:06:27.117] Timer tick, asking Buildbucket for the build status
[17:06:28.262] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:28.262] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:22:0) after 1m0s
[17:07:28.237] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:22:0)
[17:07:28.237] Timer tick, asking Buildbucket for the build status
[17:07:28.413] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:28.413] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:23:0) after 1m0s
[17:08:28.608] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:23:0)
[17:08:28.608] Timer tick, asking Buildbucket for the build status
[17:08:28.943] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:28.946] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:24:0) after 1m0s
[17:09:29.028] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:24:0)
[17:09:29.028] Timer tick, asking Buildbucket for the build status
[17:09:29.186] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:29.186] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:25:0) after 1m0s
[17:10:29.218] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:25:0)
[17:10:29.218] Timer tick, asking Buildbucket for the build status
[17:10:29.382] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:29.382] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:26:0) after 1m0s
[17:11:29.414] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:26:0)
[17:11:29.414] Timer tick, asking Buildbucket for the build status
[17:11:29.882] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:29.882] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:27:0) after 1m0s
[17:12:29.928] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:27:0)
[17:12:29.928] Timer tick, asking Buildbucket for the build status
[17:12:30.468] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:30.468] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:28:0) after 1m0s
[17:13:30.554] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:28:0)
[17:13:30.554] Timer tick, asking Buildbucket for the build status
[17:13:31.013] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:31.013] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:29:0) after 1m0s
[17:14:31.059] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:29:0)
[17:14:31.059] Timer tick, asking Buildbucket for the build status
[17:14:31.453] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:31.453] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:30:0) after 1m0s
[17:15:31.498] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:30:0)
[17:15:31.498] Timer tick, asking Buildbucket for the build status
[17:15:31.919] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:31.919] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:31:0) after 1m0s
[17:16:32.009] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:31:0)
[17:16:32.009] Timer tick, asking Buildbucket for the build status
[17:16:33.481] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:33.481] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:32:0) after 1m0s
[17:17:33.501] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:32:0)
[17:17:33.501] Timer tick, asking Buildbucket for the build status
[17:17:36.755] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:36.755] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:33:0) after 1m0s
[17:18:36.817] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:33:0)
[17:18:36.817] Timer tick, asking Buildbucket for the build status
[17:18:36.997] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:36.997] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:34:0) after 1m0s
[17:19:37.069] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:34:0)
[17:19:37.069] Timer tick, asking Buildbucket for the build status
[17:19:37.315] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:37.315] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:35:0) after 1m0s
[17:20:37.416] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:35:0)
[17:20:37.416] Timer tick, asking Buildbucket for the build status
[17:20:37.666] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:37.666] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:36:0) after 1m0s
[17:21:37.849] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:36:0)
[17:21:37.849] Timer tick, asking Buildbucket for the build status
[17:21:39.131] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:39.131] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:37:0) after 1m0s
[17:22:39.892] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:37:0)
[17:22:39.892] Timer tick, asking Buildbucket for the build status
[17:22:40.749] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:40.749] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:38:0) after 1m0s
[17:23:40.781] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:38:0)
[17:23:40.781] Timer tick, asking Buildbucket for the build status
[17:23:41.437] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:41.437] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:39:0) after 1m0s
[17:24:41.554] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:39:0)
[17:24:41.554] Timer tick, asking Buildbucket for the build status
[17:24:42.982] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:42.982] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:40:0) after 1m0s
[17:25:43.009] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:40:0)
[17:25:43.009] Timer tick, asking Buildbucket for the build status
[17:25:43.205] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:43.205] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:41:0) after 1m0s
[17:26:43.574] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:41:0)
[17:26:43.574] Timer tick, asking Buildbucket for the build status
[17:26:44.146] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:44.146] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:42:0) after 1m0s
[17:27:44.658] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:42:0)
[17:27:44.658] Timer tick, asking Buildbucket for the build status
[17:27:44.903] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:44.903] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:43:0) after 1m0s
[17:28:44.967] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:43:0)
[17:28:44.967] Timer tick, asking Buildbucket for the build status
[17:28:45.829] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:45.829] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:44:0) after 1m0s
[17:29:45.856] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:44:0)
[17:29:45.856] Timer tick, asking Buildbucket for the build status
[17:29:47.415] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:47.415] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:45:0) after 1m0s
[17:30:47.443] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:45:0)
[17:30:47.443] Timer tick, asking Buildbucket for the build status
[17:30:47.770] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:47.770] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:46:0) after 1m0s
[17:31:47.830] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:46:0)
[17:31:47.830] Timer tick, asking Buildbucket for the build status
[17:31:47.997] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:47.997] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:47:0) after 1m0s
[17:32:48.085] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:47:0)
[17:32:48.085] Timer tick, asking Buildbucket for the build status
[17:32:48.463] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:48.463] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:48:0) after 1m0s
[17:33:48.567] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:48:0)
[17:33:48.567] Timer tick, asking Buildbucket for the build status
[17:33:49.281] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:49.282] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:49:0) after 1m0s
[17:34:49.342] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:49:0)
[17:34:49.342] Timer tick, asking Buildbucket for the build status
[17:34:49.608] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:49.608] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:50:0) after 1m0s
[17:35:49.655] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:50:0)
[17:35:49.655] Timer tick, asking Buildbucket for the build status
[17:35:49.949] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:49.950] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:51:0) after 1m0s
[17:36:50.001] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:51:0)
[17:36:50.001] Timer tick, asking Buildbucket for the build status
[17:36:50.126] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:50.126] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:52:0) after 1m0s
[17:37:50.261] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:52:0)
[17:37:50.261] Timer tick, asking Buildbucket for the build status
[17:37:50.854] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:50.854] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:53:0) after 1m0s
[17:38:51.010] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:53:0)
[17:38:51.010] Timer tick, asking Buildbucket for the build status
[17:38:52.145] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:52.145] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:54:0) after 1m0s
[17:39:52.230] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:54:0)
[17:39:52.230] Timer tick, asking Buildbucket for the build status
[17:39:52.820] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:52.820] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:55:0) after 1m0s
[17:40:52.873] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:55:0)
[17:40:52.873] Timer tick, asking Buildbucket for the build status
[17:40:53.373] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:53.373] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:56:0) after 1m0s
[17:41:53.406] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:56:0)
[17:41:53.406] Timer tick, asking Buildbucket for the build status
[17:41:53.505] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:53.505] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:57:0) after 1m0s
[17:42:53.554] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:57:0)
[17:42:53.554] Timer tick, asking Buildbucket for the build status
[17:42:53.697] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:53.697] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:58:0) after 1m0s
[17:43:53.780] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:58:0)
[17:43:53.780] Timer tick, asking Buildbucket for the build status
[17:43:54.313] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:54.313] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:59:0) after 1m0s
[17:44:54.383] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:59:0)
[17:44:54.383] Timer tick, asking Buildbucket for the build status
[17:44:54.787] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:54.787] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:60:0) after 1m0s
[17:45:54.873] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:60:0)
[17:45:54.873] Timer tick, asking Buildbucket for the build status
[17:45:57.922] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:57.922] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:61:0) after 1m0s
[17:46:57.975] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:61:0)
[17:46:57.975] Timer tick, asking Buildbucket for the build status
[17:46:58.749] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:58.749] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:62:0) after 1m0s
[17:47:58.902] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:62:0)
[17:47:58.902] Timer tick, asking Buildbucket for the build status
[17:47:59.464] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:59.465] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:63:0) after 1m0s
[17:48:59.529] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:63:0)
[17:48:59.529] Timer tick, asking Buildbucket for the build status
[17:48:59.757] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:59.757] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:64:0) after 1m0s
[17:49:59.851] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:64:0)
[17:49:59.851] Timer tick, asking Buildbucket for the build status
[17:50:01.272] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:01.272] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:65:0) after 1m0s
[17:51:02.764] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:65:0)
[17:51:02.764] Timer tick, asking Buildbucket for the build status
[17:51:02.880] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:02.881] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:66:0) after 1m0s
[17:52:02.950] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:66:0)
[17:52:02.950] Timer tick, asking Buildbucket for the build status
[17:52:03.529] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:03.529] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:67:0) after 1m0s
[17:53:03.549] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:67:0)
[17:53:03.549] Timer tick, asking Buildbucket for the build status
[17:53:03.800] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:03.800] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:68:0) after 1m0s
[17:54:03.868] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:68:0)
[17:54:03.868] Timer tick, asking Buildbucket for the build status
[17:54:04.170] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:04.170] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:69:0) after 1m0s
[17:55:04.229] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:69:0)
[17:55:04.229] Timer tick, asking Buildbucket for the build status
[17:55:04.425] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:04.425] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:70:0) after 1m0s
[17:56:04.473] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:70:0)
[17:56:04.473] Timer tick, asking Buildbucket for the build status
[17:56:05.191] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:05.191] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:71:0) after 1m0s
[17:57:05.283] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:71:0)
[17:57:05.283] Timer tick, asking Buildbucket for the build status
[17:57:09.417] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:09.417] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:72:0) after 1m0s
[17:58:09.440] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:72:0)
[17:58:09.440] Timer tick, asking Buildbucket for the build status
[17:58:11.078] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:11.078] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:73:0) after 1m0s
[17:59:11.113] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:73:0)
[17:59:11.113] Timer tick, asking Buildbucket for the build status
[17:59:11.705] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:11.705] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:74:0) after 1m0s
[18:00:11.796] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:74:0)
[18:00:11.796] Timer tick, asking Buildbucket for the build status
[18:00:12.081] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:12.081] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:75:0) after 1m0s
[18:01:12.141] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:75:0)
[18:01:12.141] Timer tick, asking Buildbucket for the build status
[18:01:12.476] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:12.476] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:76:0) after 1m0s
[18:02:12.531] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:76:0)
[18:02:12.531] Timer tick, asking Buildbucket for the build status
[18:02:12.698] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:12.698] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:77:0) after 1m0s
[18:03:12.820] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:77:0)
[18:03:12.820] Timer tick, asking Buildbucket for the build status
[18:03:13.349] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:13.349] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:78:0) after 1m0s
[18:04:13.420] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:78:0)
[18:04:13.420] Timer tick, asking Buildbucket for the build status
[18:04:13.749] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:79:0) after 1m0s
[18:05:13.843] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:79:0)
[18:05:13.843] Timer tick, asking Buildbucket for the build status
[18:05:14.058] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:14.058] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:80:0) after 1m0s
[18:06:14.157] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:80:0)
[18:06:14.157] Timer tick, asking Buildbucket for the build status
[18:06:14.383] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:14.383] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:81:0) after 1m0s
[18:07:14.410] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:81:0)
[18:07:14.410] Timer tick, asking Buildbucket for the build status
[18:07:14.515] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:14.515] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:82:0) after 1m0s
[18:08:14.633] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:82:0)
[18:08:14.633] Timer tick, asking Buildbucket for the build status
[18:08:14.825] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:14.825] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:83:0) after 1m0s
[18:09:14.942] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:83:0)
[18:09:14.942] Timer tick, asking Buildbucket for the build status
[18:09:16.457] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:16.457] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:84:0) after 1m0s
[18:10:16.536] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:84:0)
[18:10:16.536] Timer tick, asking Buildbucket for the build status
[18:10:18.149] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:18.149] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:85:0) after 1m0s
[18:11:18.218] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:85:0)
[18:11:18.218] Timer tick, asking Buildbucket for the build status
[18:11:18.598] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:18.598] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:86:0) after 1m0s
[18:12:18.621] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:86:0)
[18:12:18.621] Timer tick, asking Buildbucket for the build status
[18:12:19.279] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:19.279] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:87:0) after 1m0s
[18:13:19.379] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:87:0)
[18:13:19.379] Timer tick, asking Buildbucket for the build status
[18:13:20.085] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:20.085] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:88:0) after 1m0s
[18:14:20.121] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:88:0)
[18:14:20.121] Timer tick, asking Buildbucket for the build status
[18:14:20.282] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:20.282] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:89:0) after 1m0s
[18:15:20.433] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:89:0)
[18:15:20.433] Timer tick, asking Buildbucket for the build status
[18:15:20.536] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:20.536] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:90:0) after 1m0s
[18:16:20.545] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:90:0)
[18:16:20.545] Timer tick, asking Buildbucket for the build status
[18:16:20.671] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:20.671] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:91:0) after 1m0s
[18:17:20.770] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:91:0)
[18:17:20.770] Timer tick, asking Buildbucket for the build status
[18:17:21.484] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:21.484] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:92:0) after 1m0s
[18:18:21.583] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:92:0)
[18:18:21.583] Timer tick, asking Buildbucket for the build status
[18:18:22.638] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:22.638] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:93:0) after 1m0s
[18:19:22.694] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:93:0)
[18:19:22.694] Timer tick, asking Buildbucket for the build status
[18:19:23.498] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:23.498] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:94:0) after 1m0s
[18:20:23.479] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:94:0)
[18:20:23.479] Timer tick, asking Buildbucket for the build status
[18:20:24.087] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.087] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:95:0) after 1m0s
[18:21:24.209] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:95:0)
[18:21:24.209] Timer tick, asking Buildbucket for the build status
[18:21:26.091] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:26.091] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:96:0) after 1m0s
[18:22:26.320] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:96:0)
[18:22:26.320] Timer tick, asking Buildbucket for the build status
[18:22:27.388] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:27.388] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:97:0) after 1m0s
[18:23:27.552] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:97:0)
[18:23:27.552] Timer tick, asking Buildbucket for the build status
[18:23:27.938] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:27.938] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:98:0) after 1m0s
[18:24:28.039] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:98:0)
[18:24:28.039] Timer tick, asking Buildbucket for the build status
[18:24:28.276] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:28.276] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:99:0) after 1m0s
[18:25:28.340] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:99:0)
[18:25:28.340] Timer tick, asking Buildbucket for the build status
[18:25:28.449] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:28.449] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:100:0) after 1m0s
[18:26:28.736] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:100:0)
[18:26:28.736] Timer tick, asking Buildbucket for the build status
[18:26:30.285] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:30.285] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:101:0) after 1m0s
[18:27:30.357] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:101:0)
[18:27:30.357] Timer tick, asking Buildbucket for the build status
[18:27:30.803] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:30.803] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:102:0) after 1m0s
[18:28:30.922] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:102:0)
[18:28:30.922] Timer tick, asking Buildbucket for the build status
[18:28:31.286] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:31.286] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:103:0) after 1m0s
[18:29:31.347] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:103:0)
[18:29:31.347] Timer tick, asking Buildbucket for the build status
[18:29:31.935] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:31.935] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:104:0) after 1m0s
[18:30:31.993] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:104:0)
[18:30:31.993] Timer tick, asking Buildbucket for the build status
[18:30:33.292] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:33.292] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:105:0) after 1m0s
[18:31:33.337] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:105:0)
[18:31:33.337] Timer tick, asking Buildbucket for the build status
[18:31:34.440] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:34.440] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:106:0) after 1m0s
[18:32:34.567] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:106:0)
[18:32:34.567] Timer tick, asking Buildbucket for the build status
[18:32:34.732] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:34.732] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:107:0) after 1m0s
[18:33:34.897] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:107:0)
[18:33:34.897] Timer tick, asking Buildbucket for the build status
[18:33:35.760] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:35.760] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:108:0) after 1m0s
[18:34:35.847] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:108:0)
[18:34:35.847] Timer tick, asking Buildbucket for the build status
[18:34:35.948] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:35.948] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:109:0) after 1m0s
[18:35:36.033] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:109:0)
[18:35:36.033] Timer tick, asking Buildbucket for the build status
[18:35:36.153] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:36.153] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:110:0) after 1m0s
[18:36:36.317] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:110:0)
[18:36:36.317] Timer tick, asking Buildbucket for the build status
[18:36:38.048] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:38.048] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:111:0) after 1m0s
[18:37:38.064] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:111:0)
[18:37:38.064] Timer tick, asking Buildbucket for the build status
[18:37:40.408] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:40.408] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:112:0) after 1m0s
[18:38:40.487] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:112:0)
[18:38:40.487] Timer tick, asking Buildbucket for the build status
[18:38:40.595] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:40.595] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:113:0) after 1m0s
[18:39:40.568] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:113:0)
[18:39:40.568] Timer tick, asking Buildbucket for the build status
[18:39:41.069] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:41.070] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:114:0) after 1m0s
[18:40:41.226] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:114:0)
[18:40:41.226] Timer tick, asking Buildbucket for the build status
[18:40:41.383] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:41.383] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:115:0) after 1m0s
[18:41:41.452] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:115:0)
[18:41:41.452] Timer tick, asking Buildbucket for the build status
[18:41:41.984] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:41.984] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:116:0) after 1m0s
[18:42:42.128] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:116:0)
[18:42:42.128] Timer tick, asking Buildbucket for the build status
[18:42:44.753] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:117:0) after 1m0s
[18:43:44.927] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:117:0)
[18:43:44.927] Timer tick, asking Buildbucket for the build status
[18:43:45.107] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:45.107] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:118:0) after 1m0s
[18:44:45.166] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:118:0)
[18:44:45.166] Timer tick, asking Buildbucket for the build status
[18:44:45.670] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:45.670] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:119:0) after 1m0s
[18:45:45.693] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:119:0)
[18:45:45.693] Timer tick, asking Buildbucket for the build status
[18:45:45.806] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:45.806] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:120:0) after 1m0s
[18:46:45.829] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:120:0)
[18:46:45.829] Timer tick, asking Buildbucket for the build status
[18:46:47.370] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:47.370] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:121:0) after 1m0s
[18:47:47.417] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:121:0)
[18:47:47.417] Timer tick, asking Buildbucket for the build status
[18:47:47.610] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:47.610] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:122:0) after 1m0s
[18:48:47.670] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:122:0)
[18:48:47.670] Timer tick, asking Buildbucket for the build status
[18:48:49.100] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:49.100] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:123:0) after 1m0s
[18:49:49.153] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:123:0)
[18:49:49.153] Timer tick, asking Buildbucket for the build status
[18:49:51.520] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:51.520] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:124:0) after 1m0s
[18:50:51.771] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:124:0)
[18:50:51.772] Timer tick, asking Buildbucket for the build status
[18:50:52.029] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:52.029] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:125:0) after 1m0s
[18:51:52.300] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:125:0)
[18:51:52.300] Timer tick, asking Buildbucket for the build status
[18:51:53.161] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:53.161] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:126:0) after 1m0s
[18:52:53.273] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:126:0)
[18:52:53.273] Timer tick, asking Buildbucket for the build status
[18:52:54.363] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:127:0) after 1m0s
[18:53:54.401] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:127:0)
[18:53:54.401] Timer tick, asking Buildbucket for the build status
[18:53:55.059] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:55.059] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:128:0) after 1m0s
[18:54:55.103] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:128:0)
[18:54:55.103] Timer tick, asking Buildbucket for the build status
[18:54:55.303] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:55.303] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:129:0) after 1m0s
[18:55:55.351] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:129:0)
[18:55:55.351] Timer tick, asking Buildbucket for the build status
[18:55:55.803] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:55.803] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:130:0) after 1m0s
[18:56:55.813] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:130:0)
[18:56:55.813] Timer tick, asking Buildbucket for the build status
[18:56:55.943] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:55.943] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:131:0) after 1m0s
[18:57:56.138] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:131:0)
[18:57:56.138] Timer tick, asking Buildbucket for the build status
[18:57:56.348] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:56.348] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:132:0) after 1m0s
[18:58:56.396] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:132:0)
[18:58:56.396] Timer tick, asking Buildbucket for the build status
[18:58:56.569] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:56.569] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:133:0) after 1m0s
[18:59:56.594] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:133:0)
[18:59:56.594] Timer tick, asking Buildbucket for the build status
[18:59:56.897] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:56.897] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:134:0) after 1m0s
[19:00:56.982] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:134:0)
[19:00:56.982] Timer tick, asking Buildbucket for the build status
[19:00:57.668] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:135:0) after 1m0s
[19:01:57.718] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:135:0)
[19:01:57.718] Timer tick, asking Buildbucket for the build status
[19:01:57.911] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:57.911] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:136:0) after 1m0s
[19:02:57.966] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:136:0)
[19:02:57.966] Timer tick, asking Buildbucket for the build status
[19:02:58.112] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:137:0) after 1m0s
[19:03:58.098] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:137:0)
[19:03:58.098] Timer tick, asking Buildbucket for the build status
[19:03:58.650] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:58.650] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:138:0) after 1m0s
[19:04:58.794] Handling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:138:0)
[19:04:58.794] Timer tick, asking Buildbucket for the build status
[19:05:01.072] Build 8912108108332981872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:01.072] Scheduling timer "check-buildbucket-build-status" (chromium/CFI Linux ToT:9077538171096773424:139:0) after 1m0s
[19:05:42.330] Received PubSub notification, asking Buildbucket for the build status
[19:05:43.059] Build 8912108108332981872: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:05:43.059] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559156741814911",
"created_by": "project:chromium",
"created_ts": "1559148414254747",
"failure_reason": "BUILD_FAILURE",
"id": "8912108108332981872",
"parameters_json": "{\"builder_name\": \"CFI Linux ToT\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53855e57317e88d5ae19ac9964ffa17a4c965c49\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compile') (retcode: 1)\"}, \"properties\": {\"$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-trusty-32-6-vs50\", \"branch\": \"refs/heads/master\", \"buildername\": \"CFI Linux ToT\", \"buildnumber\": 6889, \"got_angle_revision\": \"262e2824847798c7549da0f79278fe7d6c9faea7\", \"got_clang_revision\": \"361989-5b2088d1-0\", \"got_dawn_revision\": \"26d3cf08c209c662a6e2298c301272e2eb8246e4\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"53855e57317e88d5ae19ac9964ffa17a4c965c49\", \"got_revision_cp\": \"refs/heads/master@{#664305}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"2cb25737c9eb7893ac752674e75d400b4419f67c\", \"got_v8_revision_cp\": \"refs/heads/7.6.303@{#1}\", \"got_webrtc_revision\": \"85a9d91cd4cf0429baa4d9cd01af9b7d3d03d410\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28107}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53855e57317e88d5ae19ac9964ffa17a4c965c49\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3805b8ba4abc77de369f5d4399a0a76b312e2fd8c5f804cd390624b9118dfa64_v2\", \"builder_9fdc5a29922d525ac71344d8edaed852039a0b05963327c23a19e46eae86874d_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-trusty-32-6-vs50\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"Step('compile') (retcode: 1)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559148424215418",
"status": "COMPLETED",
"status_changed_ts": "1559148425248681",
"tags": [
"build_address:luci.chromium.ci/CFI Linux ToT/6889",
"builder:CFI Linux ToT",
"buildset:commit/git/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53855e57317e88d5ae19ac9964ffa17a4c965c49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077538171096773424",
"scheduler_job_id:chromium/CFI Linux ToT",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912108108332981872/+/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:451d4e50cb315510",
"user_agent:luci-scheduler"
],
"updated_ts": "1559156741980225",
"url": "https://ci.chromium.org/b/8912108108332981872",
"utcnow_ts": "1559156743044144"
}
}
[19:05:43.059] Invocation finished in 2h18m50.824141688s with status FAILED