[11:54:42.446] New invocation is queued and will start shortly
[11:54:43.565] Starting the invocation (attempt 1)
[11:54:43.601] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:54:43.603] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:54:43.603] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031080308668109296",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"289317613ad15098634c2d87e179e631a81e3c54\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9031080308668109296",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/289317613ad15098634c2d87e179e631a81e3c54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/289317613ad15098634c2d87e179e631a81e3c54",
"gitiles_ref:refs/heads/master"
]
}
[11:54:44.176] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603454083697541",
"id": "8865650246691838816",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"289317613ad15098634c2d87e179e631a81e3c54\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603454084056714",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42209",
"builder:linux-chromeos-rel",
"buildset:commit/git/289317613ad15098634c2d87e179e631a81e3c54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/289317613ad15098634c2d87e179e631a81e3c54",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031080308668109296",
"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/8865650246691838816/+/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": "1603454084056831",
"url": "https://ci.chromium.org/b/8865650246691838816",
"utcnow_ts": "1603454084168460"
}
}
[11:54:44.176] Task URL: https://ci.chromium.org/b/8865650246691838816
[11:54:44.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:2:0) after 1m0s
[11:55:44.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:2:0)
[11:55:44.211] Timer tick, asking Buildbucket for the build status
[11:55:44.336] Build 8865650246691838816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:44.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:3:0) after 1m0s
[11:56:44.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:3:0)
[11:56:44.404] Timer tick, asking Buildbucket for the build status
[11:56:44.486] Build 8865650246691838816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:56:44.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:4:0) after 1m0s
[11:57:44.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:4:0)
[11:57:44.556] Timer tick, asking Buildbucket for the build status
[11:57:44.642] Build 8865650246691838816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:44.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:5:0) after 1m0s
[11:58:44.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:5:0)
[11:58:44.677] Timer tick, asking Buildbucket for the build status
[11:58:44.756] Build 8865650246691838816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:6:0) after 1m0s
[11:59:02.930] Received PubSub notification, asking Buildbucket for the build status
[11:59:03.266] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:44.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:6:0)
[11:59:44.868] Timer tick, asking Buildbucket for the build status
[11:59:45.074] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:45.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:8:0) after 1m0s
[12:00:45.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:8:0)
[12:00:45.349] Timer tick, asking Buildbucket for the build status
[12:00:45.500] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:45.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:9:0) after 1m0s
[12:01:45.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:9:0)
[12:01:45.601] Timer tick, asking Buildbucket for the build status
[12:01:45.685] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:45.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:10:0) after 1m0s
[12:02:45.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:10:0)
[12:02:45.815] Timer tick, asking Buildbucket for the build status
[12:02:45.912] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:45.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:11:0) after 1m0s
[12:03:46.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:11:0)
[12:03:46.037] Timer tick, asking Buildbucket for the build status
[12:03:46.138] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:46.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:12:0) after 1m0s
[12:04:46.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:12:0)
[12:04:46.352] Timer tick, asking Buildbucket for the build status
[12:04:46.476] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:46.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:13:0) after 1m0s
[12:05:46.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:13:0)
[12:05:46.698] Timer tick, asking Buildbucket for the build status
[12:05:46.776] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:46.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:14:0) after 1m0s
[12:06:46.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:14:0)
[12:06:46.995] Timer tick, asking Buildbucket for the build status
[12:06:47.055] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:47.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:15:0) after 1m0s
[12:07:47.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:15:0)
[12:07:47.103] Timer tick, asking Buildbucket for the build status
[12:07:47.174] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:47.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:16:0) after 1m0s
[12:08:47.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:16:0)
[12:08:47.264] Timer tick, asking Buildbucket for the build status
[12:08:47.368] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:47.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:17:0) after 1m0s
[12:09:47.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:17:0)
[12:09:47.483] Timer tick, asking Buildbucket for the build status
[12:09:47.793] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:47.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:18:0) after 1m0s
[12:10:47.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:18:0)
[12:10:47.825] Timer tick, asking Buildbucket for the build status
[12:10:48.113] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:48.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:19:0) after 1m0s
[12:11:48.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:19:0)
[12:11:48.332] Timer tick, asking Buildbucket for the build status
[12:11:48.467] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:48.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:20:0) after 1m0s
[12:12:48.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:20:0)
[12:12:48.612] Timer tick, asking Buildbucket for the build status
[12:12:48.801] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:48.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:21:0) after 1m0s
[12:13:48.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:21:0)
[12:13:48.826] Timer tick, asking Buildbucket for the build status
[12:13:49.533] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:49.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:22:0) after 1m0s
[12:14:49.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:22:0)
[12:14:49.635] Timer tick, asking Buildbucket for the build status
[12:14:49.811] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:49.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:23:0) after 1m0s
[12:15:49.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:23:0)
[12:15:49.928] Timer tick, asking Buildbucket for the build status
[12:15:50.043] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:50.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:24:0) after 1m0s
[12:16:50.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:24:0)
[12:16:50.153] Timer tick, asking Buildbucket for the build status
[12:16:50.341] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:50.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:25:0) after 1m0s
[12:17:50.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:25:0)
[12:17:50.414] Timer tick, asking Buildbucket for the build status
[12:17:50.829] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:50.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:26:0) after 1m0s
[12:18:50.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:26:0)
[12:18:50.861] Timer tick, asking Buildbucket for the build status
[12:18:50.931] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:50.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:27:0) after 1m0s
[12:19:50.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:27:0)
[12:19:50.987] Timer tick, asking Buildbucket for the build status
[12:19:51.108] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:51.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:28:0) after 1m0s
[12:20:51.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:28:0)
[12:20:51.197] Timer tick, asking Buildbucket for the build status
[12:20:51.264] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:51.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:29:0) after 1m0s
[12:21:51.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:29:0)
[12:21:51.333] Timer tick, asking Buildbucket for the build status
[12:21:51.402] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:51.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:30:0) after 1m0s
[12:22:51.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:30:0)
[12:22:51.447] Timer tick, asking Buildbucket for the build status
[12:22:51.977] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:51.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:31:0) after 1m0s
[12:23:52.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:31:0)
[12:23:52.114] Timer tick, asking Buildbucket for the build status
[12:23:52.214] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:52.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:32:0) after 1m0s
[12:24:52.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:32:0)
[12:24:52.296] Timer tick, asking Buildbucket for the build status
[12:24:52.405] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:33:0) after 1m0s
[12:25:52.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:33:0)
[12:25:52.455] Timer tick, asking Buildbucket for the build status
[12:25:53.013] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:53.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:34:0) after 1m0s
[12:26:54.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:34:0)
[12:26:54.040] Timer tick, asking Buildbucket for the build status
[12:26:54.322] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:54.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:35:0) after 1m0s
[12:27:54.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:35:0)
[12:27:54.490] Timer tick, asking Buildbucket for the build status
[12:27:54.555] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:54.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:36:0) after 1m0s
[12:28:54.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:36:0)
[12:28:54.867] Timer tick, asking Buildbucket for the build status
[12:28:55.098] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:55.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:37:0) after 1m0s
[12:29:55.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:37:0)
[12:29:55.205] Timer tick, asking Buildbucket for the build status
[12:29:55.316] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:55.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:38:0) after 1m0s
[12:30:55.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:38:0)
[12:30:55.329] Timer tick, asking Buildbucket for the build status
[12:30:55.395] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:55.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:39:0) after 1m0s
[12:31:55.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:39:0)
[12:31:55.531] Timer tick, asking Buildbucket for the build status
[12:31:55.769] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:55.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:40:0) after 1m0s
[12:32:55.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:40:0)
[12:32:55.820] Timer tick, asking Buildbucket for the build status
[12:32:55.892] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:55.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:41:0) after 1m0s
[12:33:55.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:41:0)
[12:33:55.925] Timer tick, asking Buildbucket for the build status
[12:33:55.992] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:55.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:42:0) after 1m0s
[12:34:56.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:42:0)
[12:34:56.075] Timer tick, asking Buildbucket for the build status
[12:34:56.433] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:56.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:43:0) after 1m0s
[12:35:56.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:43:0)
[12:35:56.448] Timer tick, asking Buildbucket for the build status
[12:35:56.608] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:56.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:44:0) after 1m0s
[12:36:56.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:44:0)
[12:36:56.656] Timer tick, asking Buildbucket for the build status
[12:36:56.724] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:56.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:45:0) after 1m0s
[12:37:56.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:45:0)
[12:37:56.774] Timer tick, asking Buildbucket for the build status
[12:37:56.837] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:56.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:46:0) after 1m0s
[12:38:56.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:46:0)
[12:38:56.933] Timer tick, asking Buildbucket for the build status
[12:38:56.991] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:56.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:47:0) after 1m0s
[12:39:57.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:47:0)
[12:39:57.006] Timer tick, asking Buildbucket for the build status
[12:39:57.119] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:57.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:48:0) after 1m0s
[12:40:57.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:48:0)
[12:40:57.225] Timer tick, asking Buildbucket for the build status
[12:40:57.311] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:57.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:49:0) after 1m0s
[12:41:57.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:49:0)
[12:41:57.350] Timer tick, asking Buildbucket for the build status
[12:41:57.430] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:57.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:50:0) after 1m0s
[12:42:57.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:50:0)
[12:42:57.513] Timer tick, asking Buildbucket for the build status
[12:42:57.597] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:57.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:51:0) after 1m0s
[12:43:57.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:51:0)
[12:43:57.659] Timer tick, asking Buildbucket for the build status
[12:43:57.739] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:57.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:52:0) after 1m0s
[12:44:57.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:52:0)
[12:44:57.801] Timer tick, asking Buildbucket for the build status
[12:44:57.927] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:57.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:53:0) after 1m0s
[12:45:57.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:53:0)
[12:45:57.994] Timer tick, asking Buildbucket for the build status
[12:45:58.077] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:58.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:54:0) after 1m0s
[12:46:58.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:54:0)
[12:46:58.144] Timer tick, asking Buildbucket for the build status
[12:46:58.218] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:58.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:55:0) after 1m0s
[12:47:58.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:55:0)
[12:47:58.269] Timer tick, asking Buildbucket for the build status
[12:47:58.383] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:58.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:56:0) after 1m0s
[12:48:58.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:56:0)
[12:48:58.491] Timer tick, asking Buildbucket for the build status
[12:48:58.560] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:58.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:57:0) after 1m0s
[12:49:58.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:57:0)
[12:49:58.911] Timer tick, asking Buildbucket for the build status
[12:49:59.006] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:59.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:58:0) after 1m0s
[12:50:59.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:58:0)
[12:50:59.112] Timer tick, asking Buildbucket for the build status
[12:50:59.167] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:59.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:59:0) after 1m0s
[12:51:59.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:59:0)
[12:51:59.252] Timer tick, asking Buildbucket for the build status
[12:51:59.313] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:59.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:60:0) after 1m0s
[12:52:59.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:60:0)
[12:52:59.490] Timer tick, asking Buildbucket for the build status
[12:52:59.572] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:59.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:61:0) after 1m0s
[12:53:59.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:61:0)
[12:53:59.723] Timer tick, asking Buildbucket for the build status
[12:53:59.791] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:59.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:62:0) after 1m0s
[12:54:59.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:62:0)
[12:54:59.835] Timer tick, asking Buildbucket for the build status
[12:55:00.420] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:00.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:63:0) after 1m0s
[12:56:00.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:63:0)
[12:56:00.566] Timer tick, asking Buildbucket for the build status
[12:56:00.736] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:00.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:64:0) after 1m0s
[12:57:00.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:64:0)
[12:57:00.777] Timer tick, asking Buildbucket for the build status
[12:57:00.852] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:00.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:65:0) after 1m0s
[12:58:00.934] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:65:0)
[12:58:00.934] Timer tick, asking Buildbucket for the build status
[12:58:01.042] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:01.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:66:0) after 1m0s
[12:59:01.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:66:0)
[12:59:01.103] Timer tick, asking Buildbucket for the build status
[12:59:01.194] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:01.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:67:0) after 1m0s
[13:00:01.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:67:0)
[13:00:01.349] Timer tick, asking Buildbucket for the build status
[13:00:02.032] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:02.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:68:0) after 1m0s
[13:01:02.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:68:0)
[13:01:02.123] Timer tick, asking Buildbucket for the build status
[13:01:02.236] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:02.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:69:0) after 1m0s
[13:02:02.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:69:0)
[13:02:02.343] Timer tick, asking Buildbucket for the build status
[13:02:02.587] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:02.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:70:0) after 1m0s
[13:03:02.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:70:0)
[13:03:02.628] Timer tick, asking Buildbucket for the build status
[13:03:02.810] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:02.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:71:0) after 1m0s
[13:04:02.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:71:0)
[13:04:02.893] Timer tick, asking Buildbucket for the build status
[13:04:02.962] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:02.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:72:0) after 1m0s
[13:05:02.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:72:0)
[13:05:02.972] Timer tick, asking Buildbucket for the build status
[13:05:03.537] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:03.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:73:0) after 1m0s
[13:06:03.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:73:0)
[13:06:03.567] Timer tick, asking Buildbucket for the build status
[13:06:03.768] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:03.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:74:0) after 1m0s
[13:07:03.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:74:0)
[13:07:03.862] Timer tick, asking Buildbucket for the build status
[13:07:03.935] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:03.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:75:0) after 1m0s
[13:08:04.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:75:0)
[13:08:04.025] Timer tick, asking Buildbucket for the build status
[13:08:04.325] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:04.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:76:0) after 1m0s
[13:09:04.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:76:0)
[13:09:04.328] Timer tick, asking Buildbucket for the build status
[13:09:04.604] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:04.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:77:0) after 1m0s
[13:10:04.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:77:0)
[13:10:04.651] Timer tick, asking Buildbucket for the build status
[13:10:05.642] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:05.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:78:0) after 1m0s
[13:11:05.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:78:0)
[13:11:05.701] Timer tick, asking Buildbucket for the build status
[13:11:06.006] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:06.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:79:0) after 1m0s
[13:12:06.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:79:0)
[13:12:06.049] Timer tick, asking Buildbucket for the build status
[13:12:06.224] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:06.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:80:0) after 1m0s
[13:13:06.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:80:0)
[13:13:06.306] Timer tick, asking Buildbucket for the build status
[13:13:06.468] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:06.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:81:0) after 1m0s
[13:14:06.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:81:0)
[13:14:06.549] Timer tick, asking Buildbucket for the build status
[13:14:06.646] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:06.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:82:0) after 1m0s
[13:15:06.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:82:0)
[13:15:06.718] Timer tick, asking Buildbucket for the build status
[13:15:07.035] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:07.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:83:0) after 1m0s
[13:16:07.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:83:0)
[13:16:07.114] Timer tick, asking Buildbucket for the build status
[13:16:07.914] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:07.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:84:0) after 1m0s
[13:17:07.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:84:0)
[13:17:07.989] Timer tick, asking Buildbucket for the build status
[13:17:08.137] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:08.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:85:0) after 1m0s
[13:18:08.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:85:0)
[13:18:08.182] Timer tick, asking Buildbucket for the build status
[13:18:08.254] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:08.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:86:0) after 1m0s
[13:19:08.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:86:0)
[13:19:08.299] Timer tick, asking Buildbucket for the build status
[13:19:08.645] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:08.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:87:0) after 1m0s
[13:20:08.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:87:0)
[13:20:08.767] Timer tick, asking Buildbucket for the build status
[13:20:08.894] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:08.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:88:0) after 1m0s
[13:21:08.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:88:0)
[13:21:08.921] Timer tick, asking Buildbucket for the build status
[13:21:09.122] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:09.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:89:0) after 1m0s
[13:22:09.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:89:0)
[13:22:09.154] Timer tick, asking Buildbucket for the build status
[13:22:10.000] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:10.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:90:0) after 1m0s
[13:23:10.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:90:0)
[13:23:10.129] Timer tick, asking Buildbucket for the build status
[13:23:10.273] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:10.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:91:0) after 1m0s
[13:24:10.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:91:0)
[13:24:10.423] Timer tick, asking Buildbucket for the build status
[13:24:11.107] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:11.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:92:0) after 1m0s
[13:25:11.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:92:0)
[13:25:11.208] Timer tick, asking Buildbucket for the build status
[13:25:11.372] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:11.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:93:0) after 1m0s
[13:26:11.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:93:0)
[13:26:11.407] Timer tick, asking Buildbucket for the build status
[13:26:11.790] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:11.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:94:0) after 1m0s
[13:27:11.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:94:0)
[13:27:11.857] Timer tick, asking Buildbucket for the build status
[13:27:12.206] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:12.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:95:0) after 1m0s
[13:28:12.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:95:0)
[13:28:12.228] Timer tick, asking Buildbucket for the build status
[13:28:12.373] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:12.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:96:0) after 1m0s
[13:29:12.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:96:0)
[13:29:12.380] Timer tick, asking Buildbucket for the build status
[13:29:12.528] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:12.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:97:0) after 1m0s
[13:30:12.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:97:0)
[13:30:12.594] Timer tick, asking Buildbucket for the build status
[13:30:12.801] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:12.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:98:0) after 1m0s
[13:31:12.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:98:0)
[13:31:12.955] Timer tick, asking Buildbucket for the build status
[13:31:13.196] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:99:0) after 1m0s
[13:32:13.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:99:0)
[13:32:13.417] Timer tick, asking Buildbucket for the build status
[13:32:13.592] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:100:0) after 1m0s
[13:33:13.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:100:0)
[13:33:13.770] Timer tick, asking Buildbucket for the build status
[13:33:13.912] Build 8865650246691838816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:13.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031080308668109296:101:0) after 1m0s
[13:33:28.925] Received PubSub notification, asking Buildbucket for the build status
[13:33:29.376] Build 8865650246691838816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:33:29.376] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603460007882459",
"created_by": "project:chromium",
"created_ts": "1603454083697541",
"id": "8865650246691838816",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"289317613ad15098634c2d87e179e631a81e3c54\"}}",
"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\": \"289317613ad15098634c2d87e179e631a81e3c54\", \"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-150-top3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42209, \"got_angle_revision\": \"5641e3c185a85c04f40441698d693773b1e9e808\", \"got_dawn_revision\": \"ade9a5235c73656699662ec9371a9d42fea34ac6\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"289317613ad15098634c2d87e179e631a81e3c54\", \"got_revision_cp\": \"refs/heads/master@{#820201}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"cdd7e6fa2dcc5c9b616b5974b2d3951d9a3856b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.152@{#2}\", \"got_webrtc_revision\": \"871500da90418cc3e80e8a0eef257ce11abadd27\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32475}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"289317613ad15098634c2d87e179e631a81e3c54\", \"swarm_hashes_refs/heads/master(at){#820201}_without_patch\": {\"absl_hardening_tests\": \"0928fe151c89dd8cea4430bc81017a1505b6bc5c\", \"accessibility_unittests\": \"075012f4c498599963342f660983f71f96f1650b\", \"app_list_unittests\": \"c415e9e9e326b39ae12e6348d8d964f772b8d079\", \"app_shell_unittests\": \"7dcebe95dc5614a3dd85637f52b38a6d5235b774\", \"ash_unittests\": \"f1b7b330d9ec3fdee9d706946973fbf6b2457b46\", \"aura_unittests\": \"6081589ca5dbe9d9030b568fec91b71fef419f7a\", \"base_unittests\": \"70d3fe3efb7c84fb4267bf24eea00665ef006617\", \"base_util_unittests\": \"c9482cb022169f4dc82be4fb1fdbddc5be237c44\", \"blink_common_unittests\": \"e42ce38859179fb61617b1a4227ade3a842de37b\", \"blink_fuzzer_unittests\": \"0656229d356c7edeb3ca90ce67c083008f60fcb1\", \"blink_heap_unittests\": \"fa52df269028b56c39d916211a032812a55fa2cf\", \"blink_platform_unittests\": \"915735d6b42abd7dd35608eaa939976d9b22d514\", \"blink_unittests\": \"661dd5813ce90d3c1ab81567df2148af10c96ba1\", \"boringssl_crypto_tests\": \"0561e2160110e0eece88a506238bb96ebf217468\", \"boringssl_ssl_tests\": \"ac665b7ddcd443e76d5a9de9821587dde6c91276\", \"browser_tests\": \"0e146667b056ed7da517d5d225d56d99fb213abf\", \"capture_unittests\": \"0520dc6d525eacedeb8c99e2808ed3e75c43dc49\", \"cast_unittests\": \"b4b14afd68dd12926e2a76a207a8f2c55e1aa970\", \"cc_unittests\": \"d243002c65b80af4c15e6daf12f76c585031231b\", \"chrome_app_unittests\": \"293928244ebecdc0bfd85fd56a93a5ec1d4f4d87\", \"chromedriver_unittests\": \"7ae89facb6b5b2d32ec3ca0db11a5c4402dd58b9\", \"chromeos_components_unittests\": \"ccf4aa237db3dc89fa4f3cb7d66628c5d5e0f566\", \"chromeos_unittests\": \"18a540ea68835bed8cff6aff88a8fe8b72bfd244\", \"components_browsertests\": \"e21ddbbf646fc5936acbd7a6a9155cbeb0986938\", \"components_unittests\": \"ff0d49f09fbd5ed2bdd2f9ee5c4066571ca0cec3\", \"compositor_unittests\": \"e42d857bdc4fc420db384192b5776ae9abaf5148\", \"content_browsertests\": \"607b41a4360dc8d9d9dd106f84c785f9fd2d2192\", \"content_unittests\": \"71038717cbb48272a5dd37f719f64a7b4c94fb1f\", \"crypto_unittests\": \"a7965ebb10afabb83aa894c9c1ee5cab3562fe33\", \"dbus_unittests\": \"7072477ec425caa513c3403ba9618fcd93181f18\", \"device_unittests\": \"5aad26537e902e7ab12a03b480b7822b57aa7383\", \"display_unittests\": \"a7bae2a74cd5a01e2a545a5c23cce2d557f5d64d\", \"events_unittests\": \"0bfa87a71902953236f6e5c1640f626a4650c2b4\", \"exo_unittests\": \"532e1124631d8b9bb14958666891d8ef70720f45\", \"extensions_browsertests\": \"79774fcb88571b4804aa2420e2236226f4aa04f7\", \"extensions_unittests\": \"b827008f05a05ae633728ae2d8fc129cbc2ea0a4\", \"filesystem_service_unittests\": \"a9e51fba0c19e9196bc13bd1fd6a4d43247992f2\", \"gcm_unit_tests\": \"0d28089f41551ae4f8ef4222a6a3d647b91d9a0b\", \"gfx_unittests\": \"76d94613a46664936c47a7b188e9aabccce91377\", \"gin_unittests\": \"af7a3e3930617c585144b8e7a1bd0ab77f0d1758\", \"gl_unittests_ozone\": \"e2c471b6b7ab81055a3eb60cd7968c4d12918bf5\", \"google_apis_unittests\": \"557ce6e37c8f5ffe10bda59d7d32de90339dbf02\", \"gpu_unittests\": \"2897d3289b5c5ddd7b049da804ab62b5f1367b2b\", \"gwp_asan_unittests\": \"ce2afee098a14c92b3c5a1f1dbf278375b0380e9\", \"interactive_ui_tests\": \"499d933b5367640eec2820fd62164f0c46c903c2\", \"ipc_tests\": \"9d800671b61bf80e8f22c921e7d84478f09febb6\", \"jingle_unittests\": \"ac38b84725eec37e745559f331c6a5aec8b991d5\", \"keyboard_unittests\": \"a065a50934b438e54019a6c670906b66068a2ed0\", \"latency_unittests\": \"22146fc0070c41cd18edcb120c1562fc0bbd18c4\", \"libjingle_xmpp_unittests\": \"0ec65d648c265626a2883a99cb8a6c155f13d850\", \"media_blink_unittests\": \"38f304324f934fd60164cd38721d446cf3a9b10f\", \"media_unittests\": \"857b00be5810d655942540dde26d07e6f7542026\", \"message_center_unittests\": \"a86b60d8a970ea3c628f52fde68bea5fc67cb9dc\", \"midi_unittests\": \"b71671d06713523157b056e25479770d47f852d9\", \"mojo_core_unittests\": \"3d7d5546d201abb4c1718a6fea67895e066b9621\", \"mojo_unittests\": \"7863d969387578f7641e8bdba1b07cb0d1d6f1cb\", \"nacl_helper_nonsfi_unittests\": \"3eab5488859b10338315940486da90d706a810b2\", \"nacl_loader_unittests\": \"e83fde4f551f1ad2b05a53956a53495ca6d78746\", \"native_theme_unittests\": \"273d5c73b0853cb603b951bd7035897c81558766\", \"net_unittests\": \"a9bde68892934692f2fa25e0834a3aac6f5eda08\", \"ozone_gl_unittests\": \"5c3447bd891467f2dfe197107af36f9de5632d66\", \"ozone_unittests\": \"6558adf433758b76bb7b0692d4dbd75a70c1e5f4\", \"ozone_x11_unittests\": \"67f6142c3f7afd97951ee7496ad92b6343829f2d\", \"pdf_unittests\": \"19a41829b03c56f16080fb4935b9c89b0aac23ae\", \"perfetto_unittests\": \"c11ed8d088a73a22307d9c2b0250b44cc23e46f4\", \"ppapi_unittests\": \"f20a0863ccde19cc678512236d1ab26a1f997cfc\", \"printing_unittests\": \"55dbb6fca0d1d8e30c7c4b5731dbf9c81633cfa3\", \"remoting_unittests\": \"c6b0629a96d7fa5031c26c9f1b959ec8e8ac4656\", \"sandbox_linux_unittests\": \"bae4fb6f8ec1aa0e0d461eddae8bcdb0100a70f9\", \"service_manager_unittests\": \"7cdb4ec73de6e4c1599e491b6c1e83a67cdb5632\", \"services_unittests\": \"f57f559cceee5b5e3a092a0d972f04a57094fe16\", \"shell_dialogs_unittests\": \"f46748a40582e91860928db5a6cac07777cb060a\", \"skia_unittests\": \"7884bbc44b799613890ac87fd08a87ef93c517c8\", \"snapshot_unittests\": \"a6e3c04f64278279d489d0e658b8d93816bf822c\", \"sql_unittests\": \"4bb3a923e214af24bc76ceada79e9e4d23f574df\", \"standalone_angle_unittests\": \"7780a0f3e8e0355ec3e2f824092bb886d3c18d92\", \"storage_unittests\": \"7d8ff9f12e3aa8a146d1c3661ea58094f4769183\", \"sync_integration_tests\": \"73a6d9ded6caef57cbc185f15b9336533ee2c640\", \"traffic_annotation_auditor_unittests\": \"648cf32f7db3b307e7abc953575cb3865cb9ca29\", \"ui_base_unittests\": \"d46ef8d24e8c451a3da27ee309c0f7191faa2094\", \"ui_chromeos_unittests\": \"281a156edd117f1181904160543f776d5046b077\", \"ui_touch_selection_unittests\": \"e07ab2779bd5d92dcf8895380649a06c93f93aa1\", \"unit_tests\": \"8f64b4279f4931c497947859296ad63d63c188bc\", \"url_unittests\": \"650450e656e5f1924b529712461025ca7f365e09\", \"usage_time_limit_unittests\": \"419f89e58aee87f4162587c02c41f8e61777bbbd\", \"views_unittests\": \"cb270f63075f3902ca06a28d077ce01dab15c7d7\", \"viz_unittests\": \"c0476d38431095b05261ac252a13ed2d45aa2deb\", \"wayland_client_perftests\": \"e7216f6ea05810e90c66c61e592745e42261c22c\", \"wm_unittests\": \"d5ebde0ba7fca6632240d5dbc2133b257b10a613\", \"wtf_unittests\": \"b94e7146af676db991e673c6943bbcf1a06ddb2f\", \"zlib_unittests\": \"044d9baf97ac3874288ce461b77031bf7b6aeffb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_1ddd16e34caf7af63a74ae11ac270fe106a065da91b5066b96592bfc502e97b9_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-150-top3\"], \"image\": [\"chrome-xenial-20101600-2d400e1627f\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603454342208526",
"status": "COMPLETED",
"status_changed_ts": "1603460008452359",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42209",
"builder:linux-chromeos-rel",
"buildset:commit/git/289317613ad15098634c2d87e179e631a81e3c54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/289317613ad15098634c2d87e179e631a81e3c54",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031080308668109296",
"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/8865650246691838816/+/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:4f6e212d17516c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603460008457819",
"url": "https://ci.chromium.org/b/8865650246691838816",
"utcnow_ts": "1603460009317828"
}
}
[13:33:29.376] Invocation finished in 1h38m46.948209168s with status SUCCEEDED