[14:24:30.645] New invocation is queued and will start shortly
[14:24:31.929] Starting the invocation (attempt 1)
[14:24:31.977] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:24:31.977] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:24:31.978] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018749696425250688",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5c4bb9007e37620017ad4d8e2da74337376815cd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018749696425250688",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5c4bb9007e37620017ad4d8e2da74337376815cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c4bb9007e37620017ad4d8e2da74337376815cd",
"gitiles_ref:refs/heads/master"
]
}
[14:24:33.029] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615213472095974",
"id": "8853319634242798432",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c4bb9007e37620017ad4d8e2da74337376815cd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615213472751748",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45993",
"builder:linux-chromeos-rel",
"buildset:commit/git/5c4bb9007e37620017ad4d8e2da74337376815cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c4bb9007e37620017ad4d8e2da74337376815cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018749696425250688",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853319634242798432/+/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": "1615213472751867",
"url": "https://ci.chromium.org/b/8853319634242798432",
"utcnow_ts": "1615213473015668"
}
}
[14:24:33.029] Task URL: https://ci.chromium.org/b/8853319634242798432
[14:24:33.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:2:0) after 1m0s
[14:25:33.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:2:0)
[14:25:33.076] Timer tick, asking Buildbucket for the build status
[14:25:33.153] Build 8853319634242798432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:33.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:3:0) after 1m0s
[14:26:33.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:3:0)
[14:26:33.319] Timer tick, asking Buildbucket for the build status
[14:26:33.394] Build 8853319634242798432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:33.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:4:0) after 1m0s
[14:27:34.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:4:0)
[14:27:34.056] Timer tick, asking Buildbucket for the build status
[14:27:34.121] Build 8853319634242798432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:34.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:5:0) after 1m0s
[14:28:34.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:5:0)
[14:28:34.257] Timer tick, asking Buildbucket for the build status
[14:28:34.362] Build 8853319634242798432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:34.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:6:0) after 1m0s
[14:29:15.370] Received PubSub notification, asking Buildbucket for the build status
[14:29:15.426] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:34.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:6:0)
[14:29:34.468] Timer tick, asking Buildbucket for the build status
[14:29:34.526] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:34.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:8:0) after 1m0s
[14:30:34.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:8:0)
[14:30:34.744] Timer tick, asking Buildbucket for the build status
[14:30:35.229] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:35.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:9:0) after 1m0s
[14:31:35.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:9:0)
[14:31:35.241] Timer tick, asking Buildbucket for the build status
[14:31:35.848] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:35.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:10:0) after 1m0s
[14:32:35.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:10:0)
[14:32:35.974] Timer tick, asking Buildbucket for the build status
[14:32:36.035] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:36.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:11:0) after 1m0s
[14:33:36.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:11:0)
[14:33:36.166] Timer tick, asking Buildbucket for the build status
[14:33:36.242] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:36.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:12:0) after 1m0s
[14:34:36.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:12:0)
[14:34:36.256] Timer tick, asking Buildbucket for the build status
[14:34:36.385] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:36.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:13:0) after 1m0s
[14:35:36.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:13:0)
[14:35:36.703] Timer tick, asking Buildbucket for the build status
[14:35:36.763] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:36.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:14:0) after 1m0s
[14:36:36.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:14:0)
[14:36:36.837] Timer tick, asking Buildbucket for the build status
[14:36:36.924] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:36.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:15:0) after 1m0s
[14:37:36.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:15:0)
[14:37:36.943] Timer tick, asking Buildbucket for the build status
[14:37:37.009] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:37.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:16:0) after 1m0s
[14:38:37.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:16:0)
[14:38:37.058] Timer tick, asking Buildbucket for the build status
[14:38:37.141] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:17:0) after 1m0s
[14:39:37.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:17:0)
[14:39:37.405] Timer tick, asking Buildbucket for the build status
[14:39:37.495] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:37.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:18:0) after 1m0s
[14:40:37.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:18:0)
[14:40:37.625] Timer tick, asking Buildbucket for the build status
[14:40:37.718] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:37.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:19:0) after 1m0s
[14:41:37.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:19:0)
[14:41:37.800] Timer tick, asking Buildbucket for the build status
[14:41:37.966] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:37.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:20:0) after 1m0s
[14:42:37.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:20:0)
[14:42:37.954] Timer tick, asking Buildbucket for the build status
[14:42:38.121] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:38.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:21:0) after 1m0s
[14:43:38.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:21:0)
[14:43:38.108] Timer tick, asking Buildbucket for the build status
[14:43:38.176] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:38.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:22:0) after 1m0s
[14:44:38.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:22:0)
[14:44:38.206] Timer tick, asking Buildbucket for the build status
[14:44:38.268] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:38.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:23:0) after 1m0s
[14:45:38.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:23:0)
[14:45:38.368] Timer tick, asking Buildbucket for the build status
[14:45:38.439] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:38.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:24:0) after 1m0s
[14:46:38.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:24:0)
[14:46:38.482] Timer tick, asking Buildbucket for the build status
[14:46:38.554] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:38.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:25:0) after 1m0s
[14:47:38.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:25:0)
[14:47:38.562] Timer tick, asking Buildbucket for the build status
[14:47:38.645] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:38.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:26:0) after 1m0s
[14:48:38.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:26:0)
[14:48:38.662] Timer tick, asking Buildbucket for the build status
[14:48:39.034] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:39.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:27:0) after 1m0s
[14:49:39.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:27:0)
[14:49:39.065] Timer tick, asking Buildbucket for the build status
[14:49:39.196] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:39.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:28:0) after 1m0s
[14:50:39.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:28:0)
[14:50:39.205] Timer tick, asking Buildbucket for the build status
[14:50:39.277] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:39.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:29:0) after 1m0s
[14:51:39.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:29:0)
[14:51:39.357] Timer tick, asking Buildbucket for the build status
[14:51:39.461] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:39.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:30:0) after 1m0s
[14:52:39.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:30:0)
[14:52:39.535] Timer tick, asking Buildbucket for the build status
[14:52:41.524] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:41.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:31:0) after 1m0s
[14:53:41.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:31:0)
[14:53:41.553] Timer tick, asking Buildbucket for the build status
[14:53:41.617] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:41.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:32:0) after 1m0s
[14:54:41.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:32:0)
[14:54:41.639] Timer tick, asking Buildbucket for the build status
[14:54:42.785] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:42.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:33:0) after 1m0s
[14:55:42.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:33:0)
[14:55:42.819] Timer tick, asking Buildbucket for the build status
[14:55:42.887] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:42.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:34:0) after 1m0s
[14:56:43.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:34:0)
[14:56:43.085] Timer tick, asking Buildbucket for the build status
[14:56:43.222] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:43.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:35:0) after 1m0s
[14:57:43.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:35:0)
[14:57:43.313] Timer tick, asking Buildbucket for the build status
[14:57:43.402] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:43.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:36:0) after 1m0s
[14:58:43.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:36:0)
[14:58:43.493] Timer tick, asking Buildbucket for the build status
[14:58:43.596] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:43.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:37:0) after 1m0s
[14:59:43.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:37:0)
[14:59:43.646] Timer tick, asking Buildbucket for the build status
[14:59:43.740] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:43.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:38:0) after 1m0s
[15:00:43.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:38:0)
[15:00:43.767] Timer tick, asking Buildbucket for the build status
[15:00:43.829] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:43.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:39:0) after 1m0s
[15:01:43.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:39:0)
[15:01:43.879] Timer tick, asking Buildbucket for the build status
[15:01:43.940] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:43.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:40:0) after 1m0s
[15:02:44.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:40:0)
[15:02:44.016] Timer tick, asking Buildbucket for the build status
[15:02:44.082] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:44.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:41:0) after 1m0s
[15:03:44.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:41:0)
[15:03:44.180] Timer tick, asking Buildbucket for the build status
[15:03:45.323] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:45.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:42:0) after 1m0s
[15:04:45.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:42:0)
[15:04:45.350] Timer tick, asking Buildbucket for the build status
[15:04:45.431] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:45.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:43:0) after 1m0s
[15:05:45.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:43:0)
[15:05:45.517] Timer tick, asking Buildbucket for the build status
[15:05:45.808] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:45.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:44:0) after 1m0s
[15:06:45.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:44:0)
[15:06:45.817] Timer tick, asking Buildbucket for the build status
[15:06:46.049] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:46.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:45:0) after 1m0s
[15:07:46.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:45:0)
[15:07:46.044] Timer tick, asking Buildbucket for the build status
[15:07:46.154] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:46.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:46:0) after 1m0s
[15:08:46.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:46:0)
[15:08:46.182] Timer tick, asking Buildbucket for the build status
[15:08:46.692] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:46.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:47:0) after 1m0s
[15:09:46.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:47:0)
[15:09:46.744] Timer tick, asking Buildbucket for the build status
[15:09:47.072] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:47.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:48:0) after 1m0s
[15:10:47.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:48:0)
[15:10:47.200] Timer tick, asking Buildbucket for the build status
[15:10:47.339] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:47.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:49:0) after 1m0s
[15:11:47.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:49:0)
[15:11:47.344] Timer tick, asking Buildbucket for the build status
[15:11:48.147] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:48.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:50:0) after 1m0s
[15:12:48.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:50:0)
[15:12:48.175] Timer tick, asking Buildbucket for the build status
[15:12:48.263] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:48.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:51:0) after 1m0s
[15:13:48.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:51:0)
[15:13:48.290] Timer tick, asking Buildbucket for the build status
[15:13:48.830] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:48.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:52:0) after 1m0s
[15:14:48.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:52:0)
[15:14:48.859] Timer tick, asking Buildbucket for the build status
[15:14:48.942] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:48.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:53:0) after 1m0s
[15:15:48.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:53:0)
[15:15:48.969] Timer tick, asking Buildbucket for the build status
[15:15:49.134] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:49.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:54:0) after 1m0s
[15:16:49.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:54:0)
[15:16:49.154] Timer tick, asking Buildbucket for the build status
[15:16:49.229] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:49.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:55:0) after 1m0s
[15:17:49.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:55:0)
[15:17:49.317] Timer tick, asking Buildbucket for the build status
[15:17:49.403] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:49.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:56:0) after 1m0s
[15:18:49.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:56:0)
[15:18:49.455] Timer tick, asking Buildbucket for the build status
[15:18:49.745] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:49.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:57:0) after 1m0s
[15:19:49.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:57:0)
[15:19:49.762] Timer tick, asking Buildbucket for the build status
[15:19:49.851] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:49.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:58:0) after 1m0s
[15:20:49.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:58:0)
[15:20:49.848] Timer tick, asking Buildbucket for the build status
[15:20:49.927] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:49.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:59:0) after 1m0s
[15:21:49.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:59:0)
[15:21:49.991] Timer tick, asking Buildbucket for the build status
[15:21:50.159] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:50.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:60:0) after 1m0s
[15:22:50.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:60:0)
[15:22:50.179] Timer tick, asking Buildbucket for the build status
[15:22:50.283] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:50.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:61:0) after 1m0s
[15:23:50.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:61:0)
[15:23:50.311] Timer tick, asking Buildbucket for the build status
[15:23:50.387] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:50.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:62:0) after 1m0s
[15:24:50.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:62:0)
[15:24:50.388] Timer tick, asking Buildbucket for the build status
[15:24:50.454] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:50.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:63:0) after 1m0s
[15:25:50.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:63:0)
[15:25:50.473] Timer tick, asking Buildbucket for the build status
[15:25:50.549] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:50.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:64:0) after 1m0s
[15:26:50.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:64:0)
[15:26:50.575] Timer tick, asking Buildbucket for the build status
[15:26:50.676] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:50.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:65:0) after 1m0s
[15:27:50.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:65:0)
[15:27:50.657] Timer tick, asking Buildbucket for the build status
[15:27:50.716] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:50.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:66:0) after 1m0s
[15:28:50.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:66:0)
[15:28:50.754] Timer tick, asking Buildbucket for the build status
[15:28:50.820] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:50.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:67:0) after 1m0s
[15:29:50.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:67:0)
[15:29:50.911] Timer tick, asking Buildbucket for the build status
[15:29:51.015] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:51.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:68:0) after 1m0s
[15:30:51.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:68:0)
[15:30:51.091] Timer tick, asking Buildbucket for the build status
[15:30:51.257] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:51.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:69:0) after 1m0s
[15:31:51.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:69:0)
[15:31:51.265] Timer tick, asking Buildbucket for the build status
[15:31:51.339] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:51.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:70:0) after 1m0s
[15:32:52.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:70:0)
[15:32:52.073] Timer tick, asking Buildbucket for the build status
[15:32:52.340] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:52.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:71:0) after 1m0s
[15:33:52.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:71:0)
[15:33:52.714] Timer tick, asking Buildbucket for the build status
[15:33:52.938] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:52.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:72:0) after 1m0s
[15:34:53.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:72:0)
[15:34:53.272] Timer tick, asking Buildbucket for the build status
[15:34:54.481] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:73:0) after 1m0s
[15:35:54.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:73:0)
[15:35:54.576] Timer tick, asking Buildbucket for the build status
[15:35:54.755] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:54.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:74:0) after 1m0s
[15:36:54.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:74:0)
[15:36:54.774] Timer tick, asking Buildbucket for the build status
[15:36:54.915] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:54.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:75:0) after 1m0s
[15:37:54.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:75:0)
[15:37:54.959] Timer tick, asking Buildbucket for the build status
[15:37:55.016] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:55.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:76:0) after 1m0s
[15:38:55.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:76:0)
[15:38:55.059] Timer tick, asking Buildbucket for the build status
[15:38:55.147] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:55.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:77:0) after 1m0s
[15:39:55.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:77:0)
[15:39:55.257] Timer tick, asking Buildbucket for the build status
[15:39:55.340] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:55.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:78:0) after 1m0s
[15:40:55.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:78:0)
[15:40:55.500] Timer tick, asking Buildbucket for the build status
[15:40:57.376] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:57.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:79:0) after 1m0s
[15:41:57.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:79:0)
[15:41:57.404] Timer tick, asking Buildbucket for the build status
[15:41:57.470] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:57.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:80:0) after 1m0s
[15:42:57.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:80:0)
[15:42:57.551] Timer tick, asking Buildbucket for the build status
[15:42:57.633] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:57.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:81:0) after 1m0s
[15:43:57.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:81:0)
[15:43:57.879] Timer tick, asking Buildbucket for the build status
[15:43:57.941] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:57.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:82:0) after 1m0s
[15:44:58.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:82:0)
[15:44:58.096] Timer tick, asking Buildbucket for the build status
[15:44:59.431] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:59.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:83:0) after 1m0s
[15:45:59.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:83:0)
[15:45:59.544] Timer tick, asking Buildbucket for the build status
[15:45:59.623] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:59.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:84:0) after 1m0s
[15:46:59.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:84:0)
[15:46:59.749] Timer tick, asking Buildbucket for the build status
[15:46:59.832] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:59.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:85:0) after 1m0s
[15:47:59.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:85:0)
[15:47:59.890] Timer tick, asking Buildbucket for the build status
[15:47:59.993] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:59.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:86:0) after 1m0s
[15:49:00.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:86:0)
[15:49:00.080] Timer tick, asking Buildbucket for the build status
[15:49:00.214] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:00.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:87:0) after 1m0s
[15:50:00.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:87:0)
[15:50:00.246] Timer tick, asking Buildbucket for the build status
[15:50:00.376] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:00.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:88:0) after 1m0s
[15:51:00.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:88:0)
[15:51:00.527] Timer tick, asking Buildbucket for the build status
[15:51:00.660] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:00.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:89:0) after 1m0s
[15:52:00.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:89:0)
[15:52:00.939] Timer tick, asking Buildbucket for the build status
[15:52:01.061] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:01.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:90:0) after 1m0s
[15:53:01.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:90:0)
[15:53:01.529] Timer tick, asking Buildbucket for the build status
[15:53:01.647] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:01.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:91:0) after 1m0s
[15:54:01.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:91:0)
[15:54:01.648] Timer tick, asking Buildbucket for the build status
[15:54:01.717] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:01.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:92:0) after 1m0s
[15:55:01.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:92:0)
[15:55:01.878] Timer tick, asking Buildbucket for the build status
[15:55:02.291] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:02.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:93:0) after 1m0s
[15:56:02.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:93:0)
[15:56:02.570] Timer tick, asking Buildbucket for the build status
[15:56:02.638] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:02.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:94:0) after 1m0s
[15:57:02.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:94:0)
[15:57:02.753] Timer tick, asking Buildbucket for the build status
[15:57:02.874] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:02.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:95:0) after 1m0s
[15:58:02.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:95:0)
[15:58:02.968] Timer tick, asking Buildbucket for the build status
[15:58:03.048] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:03.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:96:0) after 1m0s
[15:59:03.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:96:0)
[15:59:03.138] Timer tick, asking Buildbucket for the build status
[15:59:03.223] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:03.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:97:0) after 1m0s
[16:00:03.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:97:0)
[16:00:03.317] Timer tick, asking Buildbucket for the build status
[16:00:03.808] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:03.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:98:0) after 1m0s
[16:01:03.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:98:0)
[16:01:03.912] Timer tick, asking Buildbucket for the build status
[16:01:04.347] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:04.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:99:0) after 1m0s
[16:02:04.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:99:0)
[16:02:04.538] Timer tick, asking Buildbucket for the build status
[16:02:04.689] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:04.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:100:0) after 1m0s
[16:03:04.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:100:0)
[16:03:04.723] Timer tick, asking Buildbucket for the build status
[16:03:04.808] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:04.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:101:0) after 1m0s
[16:04:05.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:101:0)
[16:04:05.066] Timer tick, asking Buildbucket for the build status
[16:04:05.328] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:102:0) after 1m0s
[16:05:05.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:102:0)
[16:05:05.399] Timer tick, asking Buildbucket for the build status
[16:05:05.561] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:05.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:103:0) after 1m0s
[16:06:05.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:103:0)
[16:06:05.620] Timer tick, asking Buildbucket for the build status
[16:06:05.697] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:05.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:104:0) after 1m0s
[16:07:05.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:104:0)
[16:07:05.716] Timer tick, asking Buildbucket for the build status
[16:07:06.603] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:06.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:105:0) after 1m0s
[16:08:06.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:105:0)
[16:08:06.657] Timer tick, asking Buildbucket for the build status
[16:08:08.378] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:08.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:106:0) after 1m0s
[16:09:08.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:106:0)
[16:09:08.467] Timer tick, asking Buildbucket for the build status
[16:09:08.545] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:08.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:107:0) after 1m0s
[16:10:08.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:107:0)
[16:10:08.573] Timer tick, asking Buildbucket for the build status
[16:10:08.683] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:08.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:108:0) after 1m0s
[16:11:08.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:108:0)
[16:11:08.708] Timer tick, asking Buildbucket for the build status
[16:11:08.850] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:08.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:109:0) after 1m0s
[16:12:08.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:109:0)
[16:12:08.887] Timer tick, asking Buildbucket for the build status
[16:12:08.984] Build 8853319634242798432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:08.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018749696425250688:110:0) after 1m0s
[16:12:13.479] Received PubSub notification, asking Buildbucket for the build status
[16:12:13.604] Build 8853319634242798432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:12:13.604] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615219932494222",
"created_by": "project:chromium",
"created_ts": "1615213472095974",
"id": "8853319634242798432",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c4bb9007e37620017ad4d8e2da74337376815cd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5c4bb9007e37620017ad4d8e2da74337376815cd\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-9-oniv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45993, \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"5c4bb9007e37620017ad4d8e2da74337376815cd\", \"got_revision_cp\": \"refs/heads/master@{#860707}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"583f60f2586a32144238698efe5490b34938a9d3\", \"got_v8_revision_cp\": \"refs/heads/9.1.61@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c4bb9007e37620017ad4d8e2da74337376815cd\", \"swarm_hashes_refs/heads/master(at){#860707}_without_patch\": {\"absl_hardening_tests\": \"42033aee87f14f7caae70e62ce07b154032900b0\", \"accessibility_unittests\": \"e00fc7c9b50cfdd4f6ed1a8a532483de8a98546c\", \"angle_unittests\": \"ae8a186f45d0e569998715fdeb3323f25ec421e1\", \"app_list_unittests\": \"dd0bc0e64f36846cb9906adf748c553ff26394e2\", \"app_shell_unittests\": \"e28c486886acef7083b429514488f4a463305781\", \"ash_unittests\": \"29b5c89888b477f2ceb13b802152a77925929ae7\", \"aura_unittests\": \"c756a9e4d983adb724a1e5169c9399706c5ac71c\", \"base_unittests\": \"78da33337e6dde03fcfe5a7c87c36c8d92c3baab\", \"base_util_unittests\": \"f038265b759aa2a44fb55f4b054878943e935c27\", \"blink_common_unittests\": \"116c9f32847d1a4993868f86485ee6af1d20edbc\", \"blink_fuzzer_unittests\": \"08ec21a1ff969d42f210e5b0302cf73a96a1132a\", \"blink_heap_unittests\": \"680614a6373b2df0d72a9805a233a43874287284\", \"blink_platform_unittests\": \"ffce19aa612600a1c50e23ac3b9192045127fab2\", \"blink_unittests\": \"9f2587f0d485acb17ee93f5edda5b10f78fb0c5b\", \"boringssl_crypto_tests\": \"04d26bf044b9c9cba6645718c183748dc8917c6a\", \"boringssl_ssl_tests\": \"883a3697a0e11e7f91411a460bed70f7730065e2\", \"browser_tests\": \"cea8abea8dac8ddb231439b9f1ef11291c55037b\", \"capture_unittests\": \"443cc16063e5d6b4efea547d3ed23f2fbad45792\", \"cast_unittests\": \"f1d6f417f35d6bd32cd253d024c427a1363a5286\", \"cc_unittests\": \"4e679d0fd5e79e0ee263e544a9111da93ca6cb44\", \"chrome_app_unittests\": \"1ddb2e81537cf7b182aa0d24f3823c3e018ba3b0\", \"chromedriver_unittests\": \"310242864a77034e13953a66836e0f9a5b2158b5\", \"chromeos_components_unittests\": \"8707b225157a3e7efac747ca9a4ee4bd55c83649\", \"chromeos_unittests\": \"d85afa30440d5ce00c7196200dfb6e334396a1fc\", \"color_unittests\": \"ac3824aa95cc57ae914501408bc71e3b67398f2c\", \"components_browsertests\": \"6e2ebe6a34431b5b546a186436fee2923e032553\", \"components_unittests\": \"452cc7f69f2add367d18cedfbec218ee5787cb83\", \"compositor_unittests\": \"b81ad7c72bb9b7d6d24af9609815c91380c2452e\", \"content_browsertests\": \"b71bb00ce8de05d60a51bca4771b295cdd40271d\", \"content_unittests\": \"e165e308f74b0b13757860cc18090f05f74bc203\", \"crashpad_tests\": \"af3095318e2ac3116ec37db1fc5210b253cbffa0\", \"crypto_unittests\": \"39f801dfad3f82028b384212eed182d094b979bb\", \"dbus_unittests\": \"557c61afa249290d1caa8d183c2190badb06714f\", \"device_unittests\": \"76d92c33a9d2cf81a34c933acf0cc037e1546205\", \"display_unittests\": \"552ae167805885629e5ad9dbaab6efee69803763\", \"events_unittests\": \"f7fa33adf6b3b4b875a8f0ece55f109d0e318cd8\", \"exo_unittests\": \"c5d9ecb06fe6785fdc03702465fd97a64e60cf92\", \"extensions_browsertests\": \"c6b567cb0202f3c2f23008aa5d35ce2cb247b58c\", \"extensions_unittests\": \"81eda911a4f759bc6e5a1b1b014d62d268117990\", \"filesystem_service_unittests\": \"04aa712b4f93a427b7f3187aed405690545ca3d2\", \"gcm_unit_tests\": \"e1f9d51fde9707b1d20cf8bba2cd3c1fa97a9a06\", \"gfx_unittests\": \"4b031c9eb61144b8ff2649736f77fb0be42839b0\", \"gin_unittests\": \"327e0772a6028a4881b8996fda16b71b6db6e354\", \"gl_unittests_ozone\": \"53eed5e60156d5fe198f5c43ce01070d05b9c5b2\", \"google_apis_unittests\": \"9f59738cd4624ae67b34bc1f8a300bef94825511\", \"gpu_unittests\": \"bffb86921caa8b6b58a45582fd3299dab44c0114\", \"gwp_asan_unittests\": \"dc06aa12b2d664cccf90513165fa640d4b5c18f9\", \"interactive_ui_tests\": \"c5b569d75638e635d65489c2105c7fa5dc580fa2\", \"ipc_tests\": \"526671e855b620836875a6d9fe77f6231ff77563\", \"jingle_unittests\": \"bcdbaf138b4267759fa0a7f2b6e3f980599da1f3\", \"keyboard_unittests\": \"5c2a847aeded66367acdf2c1618e8d76c84499b9\", \"latency_unittests\": \"6b873ed11ccc03b9be66ea57e1a37c58f1884309\", \"libjingle_xmpp_unittests\": \"058025b421a779e83a418359c5e4222ed321e233\", \"liburlpattern_unittests\": \"feb9f41ec9af2f92127c0d8cb7dea692021e1e48\", \"media_blink_unittests\": \"28d04aad08bdc61fc6e0bbbfad77ce1a893dfc9d\", \"media_unittests\": \"0f21a25d21c6493e1d0f9da74480c5ed56c8a283\", \"message_center_unittests\": \"fe71e446c2b4b0aabd8a40759af0908c1a089564\", \"midi_unittests\": \"63d809856d9f4f0c9914050daa2cc001f6293a9f\", \"mojo_core_unittests\": \"61182564c90cb752840c991d0e53238224a1c3e8\", \"mojo_unittests\": \"f4a1465d4b4f5f8c01b7349bf464f2aba77523d3\", \"nacl_helper_nonsfi_unittests\": \"be286d5c9bf95fd06396cd13dfb57a993fbacb37\", \"nacl_loader_unittests\": \"74a45afad0e0a748b37eed1532cb2b6cad82dad5\", \"native_theme_unittests\": \"2f391a51eaf6679bc20e9a0e1ef90528f6b2eaec\", \"net_unittests\": \"bde0da151749936d74f63d39acb573726dc5c4d7\", \"ozone_gl_unittests\": \"1c7930132e63d02062e6d996571a7a0be31fd62f\", \"ozone_unittests\": \"4eed2a21e598f83f10ff06def6e97b3ad280f776\", \"ozone_x11_unittests\": \"d83badd7c3c31427800b9e7c969036a258a5a852\", \"pdf_unittests\": \"f5ca2b0a65505c30eed71ff179f9ac65f8ef2d9a\", \"perfetto_unittests\": \"7d30025de24607f7b1359c381d0e3f19f866bdb8\", \"ppapi_unittests\": \"63a4b2a92d990d1489ae2884105354b71c44b96e\", \"printing_unittests\": \"778d3e366a501bb542b8f3e91b6700bd3608d486\", \"remoting_unittests\": \"fbbb89009881871546e44ff956799d31527f54e8\", \"sandbox_linux_unittests\": \"023c06d1e658192614e99797d9d20a0a9aeeeeff\", \"service_manager_unittests\": \"112071f5e91e98f31bb59553bfc8c14da264a498\", \"services_unittests\": \"40cf36e75072eeccbd206f4bfaf93c2d2984cbfd\", \"shell_dialogs_unittests\": \"410b59936c67a4d4a8c722f7647b47f6004f1130\", \"shell_encryption_unittests\": \"8a970c6110ce7d4908b468486e311d726372cd66\", \"skia_unittests\": \"785e4dbaed6c83a144c619f83bcb93a14d15283a\", \"snapshot_unittests\": \"81a9530fba5786c65f93ce408817fa42b798a8f2\", \"sql_unittests\": \"147f27658d54072471e57c99ccb2163e5cda7cfd\", \"storage_unittests\": \"dd509f8718c3669f686959c632614d1fb02ba818\", \"sync_integration_tests\": \"3b502ec10293af30809b2a1abf4b19f73b0d7127\", \"traffic_annotation_auditor_unittests\": \"c1932c8322864e71fc7b116a09df5ae00f34ab88\", \"ui_base_unittests\": \"f53a28b708a27683f34bbbe81e4e64c587d7d8ed\", \"ui_chromeos_unittests\": \"2f8774a1c52aa981f4ed6757bbf9bc6e82651235\", \"ui_touch_selection_unittests\": \"3747b62fff16ca5c8767d483fb507983f854b451\", \"unit_tests\": \"fdd3f0a7800f55b37f6c06fb16261cb5a47c0d32\", \"url_unittests\": \"d40284dcc4f48ffc011e2c73a1048075c611c1b3\", \"usage_time_limit_unittests\": \"bbd91edb9627fdab16fed3397fb62d64ee0baa63\", \"views_unittests\": \"a5cea8ac692c4f6f1ee3bdda1f4bdde1e7e8246d\", \"viz_unittests\": \"d6544004d17eea28e1a091d91020807e95afa74e\", \"wayland_client_perftests\": \"f8d232dfdeeb6cc51ab139bde826881ed7a8af47\", \"wayland_client_tests\": \"3096e55ba27a896654a23fb4de7e88dc27bed562\", \"wm_unittests\": \"16a0f16434c67341f6be24e2bb28eb6c02aee86a\", \"wtf_unittests\": \"dedf8c04a9b01c2f9e2e15faad06f5462087fd74\", \"zlib_unittests\": \"41f1d8fdbf0390c74e6d2133d19a39292f2a1c8f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_8a4e9d5e5a37a98f1521a3a36ddb77cf3e170b8528c1bf60d5d419f9d69b17e3_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-9-oniv\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5744-5c0a4b4\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615213754854261",
"status": "COMPLETED",
"status_changed_ts": "1615219932813661",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45993",
"builder:linux-chromeos-rel",
"buildset:commit/git/5c4bb9007e37620017ad4d8e2da74337376815cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c4bb9007e37620017ad4d8e2da74337376815cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018749696425250688",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853319634242798432/+/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:522b0b35af857110",
"user_agent:luci-scheduler"
],
"updated_ts": "1615219932832318",
"url": "https://ci.chromium.org/b/8853319634242798432",
"utcnow_ts": "1615219933590079"
}
}
[16:12:13.604] Invocation finished in 1h47m42.974176771s with status SUCCEEDED