[09:43:01.420] New invocation is queued and will start shortly
[09:43:03.010] Starting the invocation (attempt 1)
[09:43:03.069] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:43:03.069] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:43:03.069] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019039196963439056",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"359dbd01e177362bd597f383a92ab1c13fbc0ec9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019039196963439056",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"gitiles_ref:refs/heads/master"
]
}
[09:43:03.724] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614937383182540",
"id": "8853609134451262176",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"359dbd01e177362bd597f383a92ab1c13fbc0ec9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614937383550915",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45927",
"builder:linux-chromeos-rel",
"buildset:commit/git/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019039196963439056",
"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/8853609134451262176/+/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": "1614937383551037",
"url": "https://ci.chromium.org/b/8853609134451262176",
"utcnow_ts": "1614937383717553"
}
}
[09:43:03.724] Task URL: https://ci.chromium.org/b/8853609134451262176
[09:43:03.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:2:0) after 1m0s
[09:44:03.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:2:0)
[09:44:03.753] Timer tick, asking Buildbucket for the build status
[09:44:03.832] Build 8853609134451262176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:03.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:3:0) after 1m0s
[09:45:03.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:3:0)
[09:45:03.953] Timer tick, asking Buildbucket for the build status
[09:45:04.019] Build 8853609134451262176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:04.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:4:0) after 1m0s
[09:46:04.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:4:0)
[09:46:04.229] Timer tick, asking Buildbucket for the build status
[09:46:04.288] Build 8853609134451262176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:04.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:5:0) after 1m0s
[09:47:04.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:5:0)
[09:47:04.468] Timer tick, asking Buildbucket for the build status
[09:47:04.536] Build 8853609134451262176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:04.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:6:0) after 1m0s
[09:48:03.851] Received PubSub notification, asking Buildbucket for the build status
[09:48:03.969] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:04.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:6:0)
[09:48:04.742] Timer tick, asking Buildbucket for the build status
[09:48:04.823] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:04.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:8:0) after 1m0s
[09:49:05.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:8:0)
[09:49:05.540] Timer tick, asking Buildbucket for the build status
[09:49:05.652] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:05.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:9:0) after 1m0s
[09:50:05.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:9:0)
[09:50:05.924] Timer tick, asking Buildbucket for the build status
[09:50:05.998] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:05.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:10:0) after 1m0s
[09:51:06.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:10:0)
[09:51:06.001] Timer tick, asking Buildbucket for the build status
[09:51:06.104] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:06.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:11:0) after 1m0s
[09:52:06.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:11:0)
[09:52:06.219] Timer tick, asking Buildbucket for the build status
[09:52:06.369] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:06.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:12:0) after 1m0s
[09:53:06.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:12:0)
[09:53:06.463] Timer tick, asking Buildbucket for the build status
[09:53:06.638] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:06.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:13:0) after 1m0s
[09:54:06.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:13:0)
[09:54:06.653] Timer tick, asking Buildbucket for the build status
[09:54:06.818] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:06.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:14:0) after 1m0s
[09:55:06.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:14:0)
[09:55:06.841] Timer tick, asking Buildbucket for the build status
[09:55:07.129] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:07.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:15:0) after 1m0s
[09:56:07.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:15:0)
[09:56:07.146] Timer tick, asking Buildbucket for the build status
[09:56:07.434] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:07.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:16:0) after 1m0s
[09:57:07.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:16:0)
[09:57:07.459] Timer tick, asking Buildbucket for the build status
[09:57:07.529] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:07.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:17:0) after 1m0s
[09:58:07.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:17:0)
[09:58:07.531] Timer tick, asking Buildbucket for the build status
[09:58:07.732] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:07.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:18:0) after 1m0s
[09:59:07.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:18:0)
[09:59:07.822] Timer tick, asking Buildbucket for the build status
[09:59:07.886] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:07.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:19:0) after 1m0s
[10:00:07.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:19:0)
[10:00:07.958] Timer tick, asking Buildbucket for the build status
[10:00:08.055] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:08.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:20:0) after 1m0s
[10:01:08.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:20:0)
[10:01:08.158] Timer tick, asking Buildbucket for the build status
[10:01:08.234] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:08.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:21:0) after 1m0s
[10:02:08.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:21:0)
[10:02:08.347] Timer tick, asking Buildbucket for the build status
[10:02:08.542] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:08.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:22:0) after 1m0s
[10:03:08.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:22:0)
[10:03:08.673] Timer tick, asking Buildbucket for the build status
[10:03:08.854] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:08.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:23:0) after 1m0s
[10:04:08.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:23:0)
[10:04:08.869] Timer tick, asking Buildbucket for the build status
[10:04:09.307] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:09.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:24:0) after 1m0s
[10:05:09.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:24:0)
[10:05:09.374] Timer tick, asking Buildbucket for the build status
[10:05:09.602] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:09.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:25:0) after 1m0s
[10:06:09.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:25:0)
[10:06:09.620] Timer tick, asking Buildbucket for the build status
[10:06:09.924] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:26:0) after 1m0s
[10:07:09.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:26:0)
[10:07:09.967] Timer tick, asking Buildbucket for the build status
[10:07:10.074] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:10.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:27:0) after 1m0s
[10:08:10.090] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:27:0)
[10:08:10.090] Timer tick, asking Buildbucket for the build status
[10:08:10.193] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:10.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:28:0) after 1m0s
[10:09:10.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:28:0)
[10:09:10.209] Timer tick, asking Buildbucket for the build status
[10:09:10.740] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:10.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:29:0) after 1m0s
[10:10:10.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:29:0)
[10:10:10.780] Timer tick, asking Buildbucket for the build status
[10:10:10.891] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:10.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:30:0) after 1m0s
[10:11:10.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:30:0)
[10:11:10.905] Timer tick, asking Buildbucket for the build status
[10:11:11.010] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:11.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:31:0) after 1m0s
[10:12:11.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:31:0)
[10:12:11.019] Timer tick, asking Buildbucket for the build status
[10:12:11.092] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:11.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:32:0) after 1m0s
[10:13:11.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:32:0)
[10:13:11.111] Timer tick, asking Buildbucket for the build status
[10:13:11.201] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:33:0) after 1m0s
[10:14:11.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:33:0)
[10:14:11.221] Timer tick, asking Buildbucket for the build status
[10:14:11.320] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:11.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:34:0) after 1m0s
[10:15:11.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:34:0)
[10:15:11.361] Timer tick, asking Buildbucket for the build status
[10:15:11.589] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:11.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:35:0) after 1m0s
[10:16:11.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:35:0)
[10:16:11.602] Timer tick, asking Buildbucket for the build status
[10:16:11.759] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:36:0) after 1m0s
[10:17:11.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:36:0)
[10:17:11.847] Timer tick, asking Buildbucket for the build status
[10:17:11.946] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:11.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:37:0) after 1m0s
[10:18:12.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:37:0)
[10:18:12.030] Timer tick, asking Buildbucket for the build status
[10:18:12.149] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:12.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:38:0) after 1m0s
[10:19:12.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:38:0)
[10:19:12.247] Timer tick, asking Buildbucket for the build status
[10:19:12.355] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:12.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:39:0) after 1m0s
[10:20:12.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:39:0)
[10:20:12.380] Timer tick, asking Buildbucket for the build status
[10:20:12.448] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:12.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:40:0) after 1m0s
[10:21:12.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:40:0)
[10:21:12.516] Timer tick, asking Buildbucket for the build status
[10:21:12.742] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:12.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:41:0) after 1m0s
[10:22:12.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:41:0)
[10:22:12.812] Timer tick, asking Buildbucket for the build status
[10:22:12.958] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:12.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:42:0) after 1m0s
[10:23:12.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:42:0)
[10:23:12.978] Timer tick, asking Buildbucket for the build status
[10:23:13.149] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:13.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:43:0) after 1m0s
[10:24:13.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:43:0)
[10:24:13.345] Timer tick, asking Buildbucket for the build status
[10:24:13.503] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:13.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:44:0) after 1m0s
[10:25:13.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:44:0)
[10:25:13.560] Timer tick, asking Buildbucket for the build status
[10:25:13.643] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:13.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:45:0) after 1m0s
[10:26:13.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:45:0)
[10:26:13.769] Timer tick, asking Buildbucket for the build status
[10:26:13.858] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:13.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:46:0) after 1m0s
[10:27:13.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:46:0)
[10:27:13.871] Timer tick, asking Buildbucket for the build status
[10:27:13.957] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:13.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:47:0) after 1m0s
[10:28:14.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:47:0)
[10:28:14.071] Timer tick, asking Buildbucket for the build status
[10:28:14.169] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:14.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:48:0) after 1m0s
[10:29:14.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:48:0)
[10:29:14.192] Timer tick, asking Buildbucket for the build status
[10:29:14.261] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:14.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:49:0) after 1m0s
[10:30:14.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:49:0)
[10:30:14.319] Timer tick, asking Buildbucket for the build status
[10:30:14.444] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:14.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:50:0) after 1m0s
[10:31:14.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:50:0)
[10:31:14.484] Timer tick, asking Buildbucket for the build status
[10:31:14.637] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:14.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:51:0) after 1m0s
[10:32:14.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:51:0)
[10:32:14.741] Timer tick, asking Buildbucket for the build status
[10:32:14.811] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:14.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:52:0) after 1m0s
[10:33:14.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:52:0)
[10:33:14.910] Timer tick, asking Buildbucket for the build status
[10:33:15.135] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:15.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:53:0) after 1m0s
[10:34:15.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:53:0)
[10:34:15.132] Timer tick, asking Buildbucket for the build status
[10:34:15.201] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:15.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:54:0) after 1m0s
[10:35:15.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:54:0)
[10:35:15.298] Timer tick, asking Buildbucket for the build status
[10:35:15.367] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:15.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:55:0) after 1m0s
[10:36:15.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:55:0)
[10:36:15.573] Timer tick, asking Buildbucket for the build status
[10:36:15.641] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:15.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:56:0) after 1m0s
[10:37:15.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:56:0)
[10:37:15.847] Timer tick, asking Buildbucket for the build status
[10:37:15.922] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:15.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:57:0) after 1m0s
[10:38:15.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:57:0)
[10:38:15.967] Timer tick, asking Buildbucket for the build status
[10:38:16.059] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:16.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:58:0) after 1m0s
[10:39:16.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:58:0)
[10:39:16.155] Timer tick, asking Buildbucket for the build status
[10:39:16.338] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:16.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:59:0) after 1m0s
[10:40:16.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:59:0)
[10:40:16.395] Timer tick, asking Buildbucket for the build status
[10:40:16.593] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:16.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:60:0) after 1m0s
[10:41:16.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:60:0)
[10:41:16.633] Timer tick, asking Buildbucket for the build status
[10:41:16.912] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:16.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:61:0) after 1m0s
[10:42:16.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:61:0)
[10:42:16.983] Timer tick, asking Buildbucket for the build status
[10:42:17.147] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:17.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:62:0) after 1m0s
[10:43:17.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:62:0)
[10:43:17.707] Timer tick, asking Buildbucket for the build status
[10:43:17.789] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:17.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:63:0) after 1m0s
[10:44:18.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:63:0)
[10:44:18.332] Timer tick, asking Buildbucket for the build status
[10:44:18.399] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:18.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:64:0) after 1m0s
[10:45:18.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:64:0)
[10:45:18.550] Timer tick, asking Buildbucket for the build status
[10:45:18.624] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:18.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:65:0) after 1m0s
[10:46:18.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:65:0)
[10:46:18.744] Timer tick, asking Buildbucket for the build status
[10:46:18.827] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:18.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:66:0) after 1m0s
[10:47:18.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:66:0)
[10:47:18.943] Timer tick, asking Buildbucket for the build status
[10:47:19.019] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:19.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:67:0) after 1m0s
[10:48:19.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:67:0)
[10:48:19.085] Timer tick, asking Buildbucket for the build status
[10:48:19.168] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:19.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:68:0) after 1m0s
[10:49:19.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:68:0)
[10:49:19.302] Timer tick, asking Buildbucket for the build status
[10:49:19.417] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:19.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:69:0) after 1m0s
[10:50:19.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:69:0)
[10:50:19.509] Timer tick, asking Buildbucket for the build status
[10:50:19.645] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:19.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:70:0) after 1m0s
[10:51:19.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:70:0)
[10:51:19.777] Timer tick, asking Buildbucket for the build status
[10:51:19.856] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:19.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:71:0) after 1m0s
[10:52:20.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:71:0)
[10:52:20.046] Timer tick, asking Buildbucket for the build status
[10:52:20.325] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:20.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:72:0) after 1m0s
[10:53:20.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:72:0)
[10:53:20.428] Timer tick, asking Buildbucket for the build status
[10:53:20.519] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:20.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:73:0) after 1m0s
[10:54:20.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:73:0)
[10:54:20.599] Timer tick, asking Buildbucket for the build status
[10:54:20.677] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:20.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:74:0) after 1m0s
[10:55:20.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:74:0)
[10:55:20.709] Timer tick, asking Buildbucket for the build status
[10:55:20.813] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:20.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:75:0) after 1m0s
[10:56:20.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:75:0)
[10:56:20.839] Timer tick, asking Buildbucket for the build status
[10:56:20.943] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:20.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:76:0) after 1m0s
[10:57:21.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:76:0)
[10:57:21.416] Timer tick, asking Buildbucket for the build status
[10:57:23.001] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:23.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:77:0) after 1m0s
[10:58:23.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:77:0)
[10:58:23.076] Timer tick, asking Buildbucket for the build status
[10:58:23.238] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:23.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:78:0) after 1m0s
[10:59:23.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:78:0)
[10:59:23.300] Timer tick, asking Buildbucket for the build status
[10:59:23.389] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:23.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:79:0) after 1m0s
[11:00:24.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:79:0)
[11:00:24.343] Timer tick, asking Buildbucket for the build status
[11:00:24.412] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:24.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:80:0) after 1m0s
[11:01:24.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:80:0)
[11:01:24.619] Timer tick, asking Buildbucket for the build status
[11:01:24.711] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:24.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:81:0) after 1m0s
[11:02:25.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:81:0)
[11:02:25.222] Timer tick, asking Buildbucket for the build status
[11:02:25.455] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:82:0) after 1m0s
[11:03:25.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:82:0)
[11:03:25.798] Timer tick, asking Buildbucket for the build status
[11:03:25.894] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:83:0) after 1m0s
[11:04:25.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:83:0)
[11:04:25.901] Timer tick, asking Buildbucket for the build status
[11:04:25.966] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:25.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:84:0) after 1m0s
[11:05:25.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:84:0)
[11:05:25.983] Timer tick, asking Buildbucket for the build status
[11:05:26.142] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:26.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:85:0) after 1m0s
[11:06:26.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:85:0)
[11:06:26.167] Timer tick, asking Buildbucket for the build status
[11:06:26.399] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:26.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:86:0) after 1m0s
[11:07:26.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:86:0)
[11:07:26.432] Timer tick, asking Buildbucket for the build status
[11:07:26.499] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:26.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:87:0) after 1m0s
[11:08:26.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:87:0)
[11:08:26.503] Timer tick, asking Buildbucket for the build status
[11:08:26.604] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:26.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:88:0) after 1m0s
[11:09:26.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:88:0)
[11:09:26.654] Timer tick, asking Buildbucket for the build status
[11:09:26.741] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:26.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:89:0) after 1m0s
[11:10:26.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:89:0)
[11:10:26.713] Timer tick, asking Buildbucket for the build status
[11:10:26.891] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:26.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:90:0) after 1m0s
[11:11:26.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:90:0)
[11:11:26.945] Timer tick, asking Buildbucket for the build status
[11:11:28.433] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:28.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:91:0) after 1m0s
[11:12:28.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:91:0)
[11:12:28.608] Timer tick, asking Buildbucket for the build status
[11:12:28.744] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:28.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:92:0) after 1m0s
[11:13:28.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:92:0)
[11:13:28.798] Timer tick, asking Buildbucket for the build status
[11:13:28.934] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:28.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:93:0) after 1m0s
[11:14:28.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:93:0)
[11:14:28.993] Timer tick, asking Buildbucket for the build status
[11:14:29.156] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:29.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:94:0) after 1m0s
[11:15:29.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:94:0)
[11:15:29.220] Timer tick, asking Buildbucket for the build status
[11:15:29.520] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:29.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:95:0) after 1m0s
[11:16:29.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:95:0)
[11:16:29.576] Timer tick, asking Buildbucket for the build status
[11:16:31.066] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:31.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:96:0) after 1m0s
[11:17:31.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:96:0)
[11:17:31.155] Timer tick, asking Buildbucket for the build status
[11:17:31.310] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:31.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:97:0) after 1m0s
[11:18:31.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:97:0)
[11:18:31.391] Timer tick, asking Buildbucket for the build status
[11:18:31.554] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:31.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:98:0) after 1m0s
[11:19:31.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:98:0)
[11:19:31.641] Timer tick, asking Buildbucket for the build status
[11:19:31.735] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:31.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:99:0) after 1m0s
[11:20:31.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:99:0)
[11:20:31.757] Timer tick, asking Buildbucket for the build status
[11:20:31.840] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:31.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:100:0) after 1m0s
[11:21:31.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:100:0)
[11:21:31.980] Timer tick, asking Buildbucket for the build status
[11:21:32.146] Build 8853609134451262176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:32.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019039196963439056:101:0) after 1m0s
[11:21:43.475] Received PubSub notification, asking Buildbucket for the build status
[11:21:43.566] Build 8853609134451262176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:21:43.567] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614943302804301",
"created_by": "project:chromium",
"created_ts": "1614937383182540",
"id": "8853609134451262176",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"359dbd01e177362bd597f383a92ab1c13fbc0ec9\"}}",
"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\": \"359dbd01e177362bd597f383a92ab1c13fbc0ec9\", \"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-east1-d-8-63-2rco\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45927, \"got_angle_revision\": \"fe2efefac498b56334ad167f174c32dff8914f48\", \"got_dawn_revision\": \"80915849ce7d5f4c0fe834924ed420ed0002b210\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"359dbd01e177362bd597f383a92ab1c13fbc0ec9\", \"got_revision_cp\": \"refs/heads/master@{#860168}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"3ada4ab958536c310f49288abe197d79f1bfec9a\", \"got_v8_revision_cp\": \"refs/heads/9.1.51@{#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\": \"359dbd01e177362bd597f383a92ab1c13fbc0ec9\", \"swarm_hashes_refs/heads/master(at){#860168}_without_patch\": {\"absl_hardening_tests\": \"a34f25c4b4d8ffef105b6f5087befcbd4bb70c39\", \"accessibility_unittests\": \"bb085c0fca26ac541c4689a994fc03a2ff6c72ed\", \"angle_unittests\": \"1047e945deedb424f67acf592249bb56e425244e\", \"app_list_unittests\": \"24f23ec916fca503e63a15014dbaf631c9c38c5d\", \"app_shell_unittests\": \"9229d646a69e5194c42e3b6208e23d504b87fbbc\", \"ash_unittests\": \"afdb908dc2bf0c68a54c83285ae37e70dd4e2132\", \"aura_unittests\": \"996da1b53906ad6d38ea675a1ae0927fad3001a1\", \"base_unittests\": \"80f8a4cc3df0e5cac79e439048a090054f2de8ae\", \"base_util_unittests\": \"08cca431a5bb9a6fe0ff2cc249b9e7e501d737c7\", \"blink_common_unittests\": \"7f410f3d2cf3ea78393200f65ff27b38be5c90aa\", \"blink_fuzzer_unittests\": \"c920bcb1907af5c6a0322e8da8484301fe987750\", \"blink_heap_unittests\": \"a940166eebdf0bb3ead6f9fe17124a967f87a087\", \"blink_platform_unittests\": \"ea60e6fd3f68752ee1bcc6a39f742ad615482efa\", \"blink_unittests\": \"5bba2770fa54e0061a35788261caae5bfdc66530\", \"boringssl_crypto_tests\": \"0443d9f0d02a9ae5d16159bd97085a54a34bc388\", \"boringssl_ssl_tests\": \"9c8dd3a2e21fa3100732aca076fd95bda9700bcc\", \"browser_tests\": \"9efe4be91944b4f7923d3a9eff184415e9ebb4b9\", \"capture_unittests\": \"58596fe9722888c25a3ce98f39aeb06621228ecd\", \"cast_unittests\": \"1a0f791e7cf3e72c80b1f0c00e82dc7cb125c86c\", \"cc_unittests\": \"13b581073898b13827b26037bfc69fa37478cde7\", \"chrome_app_unittests\": \"d085a5bd4e8e3ee4421349d85fa7528f40d1582b\", \"chromedriver_unittests\": \"aaf20b60356636b28e33cf5faa7c4feac7714b45\", \"chromeos_components_unittests\": \"93b530dcf86722f6c3806bd1ad44ecb1a3591075\", \"chromeos_unittests\": \"fd3972024c5bac3519dba20196c2b1972d648cc9\", \"color_unittests\": \"ea4d38ad2dfee1adceb43423d4d17f96a4134397\", \"components_browsertests\": \"70a8b70a0e6c12026992152e5a1cdbaeba3ce602\", \"components_unittests\": \"ae8c04afb3c81ad5c5369fde44c5b73578911107\", \"compositor_unittests\": \"bbeb98151ab285b3a23dd97b876e9cbf61083e47\", \"content_browsertests\": \"f4fb88727907f49702c93c49c96edcd79fef7fd4\", \"content_unittests\": \"57a553ddcb171d6ff387d7589b6736e49a6959f2\", \"crashpad_tests\": \"cd123e7297347a1b94ec9c1047e671fe2eaba271\", \"crypto_unittests\": \"3d902116fbda740ef25340df8d4e40a7cb625d26\", \"dbus_unittests\": \"b68b7766e5b4b459e0b335b7bd58d28400bc10da\", \"device_unittests\": \"cb9eb923f4139d71e400df962408da49323366a7\", \"display_unittests\": \"963ffa90c04476e202e0ab11aeedbdcde96ec0ac\", \"events_unittests\": \"37ee3708fb39f194969101dd9ae246beaa121e63\", \"exo_unittests\": \"35be93d9c08bd7c70729001474d942a0820c81cd\", \"extensions_browsertests\": \"8687db6ddfc39c6e0d0245844ef2b4379af0cced\", \"extensions_unittests\": \"38fcb3611c07fa25c6128094a38812463e4e43c7\", \"filesystem_service_unittests\": \"68260ba236a6910fc6ef4cfbcca16a6927861654\", \"gcm_unit_tests\": \"1ec8d7252ee11948d6692ed0626138ad3df22f2a\", \"gfx_unittests\": \"aa2c03e77b0c6abc0079d3210e1f36245861fc24\", \"gin_unittests\": \"a9ee626abe1ebd129e16cee0bf7943c11d5c497d\", \"gl_unittests_ozone\": \"d16a55a9f9df9edf535fc10bf00760ebab85264e\", \"google_apis_unittests\": \"b7eea5a65fd1a06347426a061b93af7567ad9767\", \"gpu_unittests\": \"4de4251239a857b5442f91019f8c087f063c5d61\", \"gwp_asan_unittests\": \"992afa58cf72ec526bcf78445f1b25458e5aaa86\", \"interactive_ui_tests\": \"9cb9af4b3f080f0fdec662afda768c2dfc40a67d\", \"ipc_tests\": \"ec7dd191a5fd7af155e71bd64328881421cefc17\", \"jingle_unittests\": \"39d95eb431d5325d22cd88b61cbec68aaad4563f\", \"keyboard_unittests\": \"70b9346a032084f8bd4a44d01743b27d82cca240\", \"latency_unittests\": \"9d502dea28be49384c3d293f4391fed3aa0c4df1\", \"libjingle_xmpp_unittests\": \"d970375aa92f2b6b4fcf493493b9af11f5082403\", \"liburlpattern_unittests\": \"b4080c8fd6220aa18538c207b60ce69d3a846eb3\", \"media_blink_unittests\": \"fb530f086879e9c43a3e1b19d4285bfe2c7a23ac\", \"media_unittests\": \"a27bdb141264b700ceaefc06de7b26564b9d4e49\", \"message_center_unittests\": \"bda25739747a0a6732499a64ddcc3894e555bf1d\", \"midi_unittests\": \"4413f1c0333b6990e2c66ee2592b1c16fc1a333e\", \"mojo_core_unittests\": \"f5e8f47af0edfaf0f3c371afa16b5ee19ceebb47\", \"mojo_unittests\": \"c2f387d57757a1d7abe8362c03f656cdcd625f38\", \"nacl_helper_nonsfi_unittests\": \"7f5815bc6a850df8116b776b891c4821387a9bd1\", \"nacl_loader_unittests\": \"c1a12c7476815a56e0d0faf824868e7a0a7eb73c\", \"native_theme_unittests\": \"1b4d6a42d5c60f54f1c8757cf7b4732142dc670f\", \"net_unittests\": \"e267347cb1896f38c31bf2c4186f9701bbfd197f\", \"ozone_gl_unittests\": \"e7f7d2694b2b98d6d6f4bc32561713d83d86a636\", \"ozone_unittests\": \"8cf070003f05c5aedd2a953a0bdc0b086da266dc\", \"ozone_x11_unittests\": \"9f6dd8969bf8c39baf6694a9549c45252f879c78\", \"pdf_unittests\": \"94ee910501df62116e12e5e2e1d89e8ae84c30db\", \"perfetto_unittests\": \"31ac47d69a6f47eae768fe7c02faeff21ad9a50c\", \"ppapi_unittests\": \"0bcf0ef8edafdee5823278e3cc6d0a7811ea4853\", \"printing_unittests\": \"79945d030b9f5fdf5342003f250a5fafbf586e4c\", \"remoting_unittests\": \"78f93d535ccb2db7063626cc78f7301c884ea207\", \"sandbox_linux_unittests\": \"b6d390eb57be021eaa4b169f0c188d8d65a6b300\", \"service_manager_unittests\": \"a657e42baaeeaa1a4396fe168bcd1611360da22d\", \"services_unittests\": \"9e532dac2bf3105a9708480e2d4e303090967d75\", \"shell_dialogs_unittests\": \"0f96cf70a9d9b341d4690a37e4b764bc398f370c\", \"shell_encryption_unittests\": \"4c8899fd698af2a6bba64b490f0deee1a9cbe799\", \"skia_unittests\": \"6b6dae86c2ad492b1367fe19e37c0fb2b5197297\", \"snapshot_unittests\": \"314a2504f372751ced52f0da1d8286102957af24\", \"sql_unittests\": \"162c6db842cb36fd769dc5ee92ea4ed310100604\", \"storage_unittests\": \"d9c9703163af548d391caa50c4767a3ae9105a0c\", \"sync_integration_tests\": \"a2033da241f5d489d62bb5f6a88f54b7947cb60a\", \"traffic_annotation_auditor_unittests\": \"d7074c7073ed3e04c8d01971c7f0da495510e872\", \"ui_base_unittests\": \"49f1c6495bfc7876d01add5dbc26136a9f6c0c26\", \"ui_chromeos_unittests\": \"fe5194361df15a5a4be95eb7a8ab134c886eeae1\", \"ui_touch_selection_unittests\": \"e543159305b5a8ee7a8e655d3b4403b5bcf832e0\", \"unit_tests\": \"729b68d927aa01f49befca30b84bcf8c71e67273\", \"url_unittests\": \"01b579d4696d9ebe3768e0a9c9bec004ed8a0979\", \"usage_time_limit_unittests\": \"266f748045968153d74e5572e19eeb9bd3a17817\", \"views_unittests\": \"a6962152809a5736fe73ace6ab6bc4e04f0a1c42\", \"viz_unittests\": \"d35ba915219680ba8dcc8bce8992097cdfb9f0b8\", \"wayland_client_perftests\": \"b9046a1950e21645b99f5f3caba451b008334f43\", \"wayland_client_tests\": \"d37068347c0d5511af31c34f2ee9fb4388ad68d1\", \"wm_unittests\": \"96ba846bb358f1b0ea26243aa4d1620942089e83\", \"wtf_unittests\": \"2bc3b57e9c01391e95d15c1747d0fc2a61b46bd7\", \"zlib_unittests\": \"f10ae8f4a24549481be071324b7ff8af44668598\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_19c2cd0a730e4dd30eecb6e2131d9290828ee5c227c19120091d62232deeb181_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_b113f2ba74b645396d20a53e30fea3b3f4dbe0717301c9938984732251717657_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-63-2rco\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614937683053757",
"status": "COMPLETED",
"status_changed_ts": "1614943303045653",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45927",
"builder:linux-chromeos-rel",
"buildset:commit/git/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/359dbd01e177362bd597f383a92ab1c13fbc0ec9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019039196963439056",
"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/8853609134451262176/+/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:521a966bec902410",
"user_agent:luci-scheduler"
],
"updated_ts": "1614943303051756",
"url": "https://ci.chromium.org/b/8853609134451262176",
"utcnow_ts": "1614943303553261"
}
}
[11:21:43.567] Invocation finished in 1h38m42.164747118s with status SUCCEEDED