[08:21:37.268] New invocation is queued and will start shortly
[08:21:38.781] Starting the invocation (attempt 1)
[08:21:38.813] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:21:38.813] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:21:38.813] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031003117930605552",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9031003117930605552",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"gitiles_ref:refs/heads/master"
]
}
[08:21:39.521] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603527698929012",
"id": "8865573055526431936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603527699364566",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42227",
"builder:linux-chromeos-rel",
"buildset:commit/git/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031003117930605552",
"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/8865573055526431936/+/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": "1603527699364787",
"url": "https://ci.chromium.org/b/8865573055526431936",
"utcnow_ts": "1603527699510062"
}
}
[08:21:39.521] Task URL: https://ci.chromium.org/b/8865573055526431936
[08:21:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:2:0) after 1m0s
[08:22:39.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:2:0)
[08:22:39.541] Timer tick, asking Buildbucket for the build status
[08:22:39.826] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:39.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:3:0) after 1m0s
[08:23:39.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:3:0)
[08:23:39.897] Timer tick, asking Buildbucket for the build status
[08:23:39.978] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:39.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:4:0) after 1m0s
[08:24:39.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:4:0)
[08:24:39.960] Timer tick, asking Buildbucket for the build status
[08:24:40.084] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:40.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:5:0) after 1m0s
[08:25:40.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:5:0)
[08:25:40.108] Timer tick, asking Buildbucket for the build status
[08:25:40.235] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:40.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:6:0) after 1m0s
[08:26:40.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:6:0)
[08:26:40.255] Timer tick, asking Buildbucket for the build status
[08:26:40.351] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:40.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:7:0) after 1m0s
[08:27:40.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:7:0)
[08:27:40.371] Timer tick, asking Buildbucket for the build status
[08:27:40.473] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:40.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:8:0) after 1m0s
[08:28:40.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:8:0)
[08:28:40.494] Timer tick, asking Buildbucket for the build status
[08:28:40.979] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:40.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:9:0) after 1m0s
[08:29:43.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:9:0)
[08:29:43.018] Timer tick, asking Buildbucket for the build status
[08:29:43.096] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:43.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:10:0) after 1m0s
[08:30:43.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:10:0)
[08:30:43.176] Timer tick, asking Buildbucket for the build status
[08:30:43.378] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:43.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:11:0) after 1m0s
[08:31:43.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:11:0)
[08:31:43.400] Timer tick, asking Buildbucket for the build status
[08:31:43.512] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:43.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:12:0) after 1m0s
[08:32:43.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:12:0)
[08:32:43.551] Timer tick, asking Buildbucket for the build status
[08:32:43.656] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:43.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:13:0) after 1m0s
[08:33:43.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:13:0)
[08:33:43.665] Timer tick, asking Buildbucket for the build status
[08:33:43.818] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:43.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:14:0) after 1m0s
[08:34:43.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:14:0)
[08:34:43.853] Timer tick, asking Buildbucket for the build status
[08:34:43.956] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:43.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:15:0) after 1m0s
[08:35:44.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:15:0)
[08:35:44.015] Timer tick, asking Buildbucket for the build status
[08:35:44.409] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:44.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:16:0) after 1m0s
[08:36:44.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:16:0)
[08:36:44.487] Timer tick, asking Buildbucket for the build status
[08:36:44.591] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:44.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:17:0) after 1m0s
[08:37:45.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:17:0)
[08:37:45.955] Timer tick, asking Buildbucket for the build status
[08:37:46.049] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:46.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:18:0) after 1m0s
[08:38:46.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:18:0)
[08:38:46.315] Timer tick, asking Buildbucket for the build status
[08:38:46.501] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:46.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:19:0) after 1m0s
[08:39:46.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:19:0)
[08:39:46.697] Timer tick, asking Buildbucket for the build status
[08:39:46.995] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:46.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:20:0) after 1m0s
[08:40:47.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:20:0)
[08:40:47.112] Timer tick, asking Buildbucket for the build status
[08:40:47.332] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:47.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:21:0) after 1m0s
[08:41:47.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:21:0)
[08:41:47.405] Timer tick, asking Buildbucket for the build status
[08:41:47.685] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:47.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:22:0) after 1m0s
[08:42:47.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:22:0)
[08:42:47.712] Timer tick, asking Buildbucket for the build status
[08:42:47.790] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:47.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:23:0) after 1m0s
[08:43:47.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:23:0)
[08:43:47.853] Timer tick, asking Buildbucket for the build status
[08:43:47.962] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:47.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:24:0) after 1m0s
[08:44:48.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:24:0)
[08:44:48.097] Timer tick, asking Buildbucket for the build status
[08:44:48.410] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:48.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:25:0) after 1m0s
[08:45:48.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:25:0)
[08:45:48.752] Timer tick, asking Buildbucket for the build status
[08:45:48.844] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:48.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:26:0) after 1m0s
[08:46:48.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:26:0)
[08:46:48.912] Timer tick, asking Buildbucket for the build status
[08:46:49.052] Build 8865573055526431936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:49.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:27:0) after 1m0s
[08:47:37.396] Received PubSub notification, asking Buildbucket for the build status
[08:47:37.459] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:49.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:27:0)
[08:47:49.088] Timer tick, asking Buildbucket for the build status
[08:47:49.203] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:49.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:29:0) after 1m0s
[08:48:49.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:29:0)
[08:48:49.314] Timer tick, asking Buildbucket for the build status
[08:48:49.390] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:49.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:30:0) after 1m0s
[08:49:49.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:30:0)
[08:49:49.422] Timer tick, asking Buildbucket for the build status
[08:49:49.520] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:49.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:31:0) after 1m0s
[08:50:49.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:31:0)
[08:50:49.571] Timer tick, asking Buildbucket for the build status
[08:50:49.652] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:49.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:32:0) after 1m0s
[08:51:49.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:32:0)
[08:51:49.690] Timer tick, asking Buildbucket for the build status
[08:51:49.796] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:49.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:33:0) after 1m0s
[08:52:49.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:33:0)
[08:52:49.871] Timer tick, asking Buildbucket for the build status
[08:52:49.936] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:49.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:34:0) after 1m0s
[08:53:50.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:34:0)
[08:53:50.059] Timer tick, asking Buildbucket for the build status
[08:53:50.128] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:50.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:35:0) after 1m0s
[08:54:50.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:35:0)
[08:54:50.244] Timer tick, asking Buildbucket for the build status
[08:54:50.513] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:50.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:36:0) after 1m0s
[08:55:50.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:36:0)
[08:55:50.517] Timer tick, asking Buildbucket for the build status
[08:55:50.573] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:50.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:37:0) after 1m0s
[08:56:50.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:37:0)
[08:56:50.633] Timer tick, asking Buildbucket for the build status
[08:56:50.743] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:50.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:38:0) after 1m0s
[08:57:50.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:38:0)
[08:57:50.876] Timer tick, asking Buildbucket for the build status
[08:57:51.016] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:51.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:39:0) after 1m0s
[08:58:51.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:39:0)
[08:58:51.038] Timer tick, asking Buildbucket for the build status
[08:58:51.112] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:51.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:40:0) after 1m0s
[08:59:51.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:40:0)
[08:59:51.184] Timer tick, asking Buildbucket for the build status
[08:59:51.264] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:51.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:41:0) after 1m0s
[09:00:51.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:41:0)
[09:00:51.361] Timer tick, asking Buildbucket for the build status
[09:00:51.562] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:51.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:42:0) after 1m0s
[09:01:51.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:42:0)
[09:01:51.641] Timer tick, asking Buildbucket for the build status
[09:01:51.748] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:51.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:43:0) after 1m0s
[09:02:51.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:43:0)
[09:02:51.830] Timer tick, asking Buildbucket for the build status
[09:02:51.923] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:44:0) after 1m0s
[09:03:51.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:44:0)
[09:03:51.947] Timer tick, asking Buildbucket for the build status
[09:03:52.056] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:52.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:45:0) after 1m0s
[09:04:52.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:45:0)
[09:04:52.137] Timer tick, asking Buildbucket for the build status
[09:04:52.203] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:52.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:46:0) after 1m0s
[09:05:52.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:46:0)
[09:05:52.226] Timer tick, asking Buildbucket for the build status
[09:05:52.296] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:52.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:47:0) after 1m0s
[09:06:52.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:47:0)
[09:06:52.319] Timer tick, asking Buildbucket for the build status
[09:06:52.512] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:52.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:48:0) after 1m0s
[09:07:52.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:48:0)
[09:07:52.531] Timer tick, asking Buildbucket for the build status
[09:07:52.582] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:52.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:49:0) after 1m0s
[09:08:52.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:49:0)
[09:08:52.638] Timer tick, asking Buildbucket for the build status
[09:08:53.138] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:53.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:50:0) after 1m0s
[09:09:53.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:50:0)
[09:09:53.127] Timer tick, asking Buildbucket for the build status
[09:09:53.192] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:53.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:51:0) after 1m0s
[09:10:53.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:51:0)
[09:10:53.193] Timer tick, asking Buildbucket for the build status
[09:10:53.258] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:53.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:52:0) after 1m0s
[09:11:53.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:52:0)
[09:11:53.304] Timer tick, asking Buildbucket for the build status
[09:11:53.415] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:53.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:53:0) after 1m0s
[09:12:53.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:53:0)
[09:12:53.417] Timer tick, asking Buildbucket for the build status
[09:12:53.471] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:53.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:54:0) after 1m0s
[09:13:53.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:54:0)
[09:13:53.492] Timer tick, asking Buildbucket for the build status
[09:13:53.589] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:53.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:55:0) after 1m0s
[09:14:53.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:55:0)
[09:14:53.652] Timer tick, asking Buildbucket for the build status
[09:14:53.777] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:53.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:56:0) after 1m0s
[09:15:53.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:56:0)
[09:15:53.780] Timer tick, asking Buildbucket for the build status
[09:15:53.840] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:53.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:57:0) after 1m0s
[09:16:53.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:57:0)
[09:16:53.870] Timer tick, asking Buildbucket for the build status
[09:16:54.389] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:54.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:58:0) after 1m0s
[09:17:54.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:58:0)
[09:17:54.414] Timer tick, asking Buildbucket for the build status
[09:17:54.472] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:54.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:59:0) after 1m0s
[09:18:54.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:59:0)
[09:18:54.526] Timer tick, asking Buildbucket for the build status
[09:18:54.726] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:60:0) after 1m0s
[09:19:54.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:60:0)
[09:19:54.713] Timer tick, asking Buildbucket for the build status
[09:19:54.781] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:54.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:61:0) after 1m0s
[09:20:54.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:61:0)
[09:20:54.801] Timer tick, asking Buildbucket for the build status
[09:20:54.923] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:54.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:62:0) after 1m0s
[09:21:54.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:62:0)
[09:21:54.944] Timer tick, asking Buildbucket for the build status
[09:21:55.055] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:55.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:63:0) after 1m0s
[09:22:55.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:63:0)
[09:22:55.078] Timer tick, asking Buildbucket for the build status
[09:22:55.280] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:55.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:64:0) after 1m0s
[09:23:55.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:64:0)
[09:23:55.315] Timer tick, asking Buildbucket for the build status
[09:23:55.520] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:55.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:65:0) after 1m0s
[09:24:55.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:65:0)
[09:24:55.566] Timer tick, asking Buildbucket for the build status
[09:24:55.705] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:55.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:66:0) after 1m0s
[09:25:55.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:66:0)
[09:25:55.732] Timer tick, asking Buildbucket for the build status
[09:25:55.799] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:55.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:67:0) after 1m0s
[09:26:55.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:67:0)
[09:26:55.858] Timer tick, asking Buildbucket for the build status
[09:26:55.980] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:55.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:68:0) after 1m0s
[09:27:56.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:68:0)
[09:27:56.061] Timer tick, asking Buildbucket for the build status
[09:27:56.136] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:56.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:69:0) after 1m0s
[09:28:56.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:69:0)
[09:28:56.188] Timer tick, asking Buildbucket for the build status
[09:28:56.501] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:56.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:70:0) after 1m0s
[09:29:56.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:70:0)
[09:29:56.523] Timer tick, asking Buildbucket for the build status
[09:29:56.634] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:56.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:71:0) after 1m0s
[09:30:56.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:71:0)
[09:30:56.660] Timer tick, asking Buildbucket for the build status
[09:30:56.729] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:56.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:72:0) after 1m0s
[09:31:56.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:72:0)
[09:31:56.757] Timer tick, asking Buildbucket for the build status
[09:31:56.858] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:56.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:73:0) after 1m0s
[09:32:56.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:73:0)
[09:32:56.914] Timer tick, asking Buildbucket for the build status
[09:32:57.081] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:57.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:74:0) after 1m0s
[09:33:57.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:74:0)
[09:33:57.099] Timer tick, asking Buildbucket for the build status
[09:33:57.156] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:57.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:75:0) after 1m0s
[09:34:57.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:75:0)
[09:34:57.186] Timer tick, asking Buildbucket for the build status
[09:34:57.245] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:57.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:76:0) after 1m0s
[09:35:57.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:76:0)
[09:35:57.235] Timer tick, asking Buildbucket for the build status
[09:35:57.485] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:57.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:77:0) after 1m0s
[09:36:57.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:77:0)
[09:36:57.512] Timer tick, asking Buildbucket for the build status
[09:36:57.606] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:57.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:78:0) after 1m0s
[09:37:57.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:78:0)
[09:37:57.628] Timer tick, asking Buildbucket for the build status
[09:37:57.707] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:57.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:79:0) after 1m0s
[09:38:57.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:79:0)
[09:38:57.732] Timer tick, asking Buildbucket for the build status
[09:38:57.807] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:57.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:80:0) after 1m0s
[09:39:57.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:80:0)
[09:39:57.828] Timer tick, asking Buildbucket for the build status
[09:39:57.894] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:57.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:81:0) after 1m0s
[09:40:57.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:81:0)
[09:40:57.920] Timer tick, asking Buildbucket for the build status
[09:40:58.240] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:58.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:82:0) after 1m0s
[09:41:58.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:82:0)
[09:41:58.260] Timer tick, asking Buildbucket for the build status
[09:41:58.614] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:58.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:83:0) after 1m0s
[09:42:58.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:83:0)
[09:42:58.638] Timer tick, asking Buildbucket for the build status
[09:42:58.706] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:58.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:84:0) after 1m0s
[09:43:58.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:84:0)
[09:43:58.817] Timer tick, asking Buildbucket for the build status
[09:43:58.904] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:58.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:85:0) after 1m0s
[09:44:58.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:85:0)
[09:44:58.960] Timer tick, asking Buildbucket for the build status
[09:45:00.405] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:00.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:86:0) after 1m0s
[09:46:00.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:86:0)
[09:46:00.492] Timer tick, asking Buildbucket for the build status
[09:46:00.574] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:00.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:87:0) after 1m0s
[09:47:00.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:87:0)
[09:47:00.635] Timer tick, asking Buildbucket for the build status
[09:47:00.873] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:00.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:88:0) after 1m0s
[09:48:00.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:88:0)
[09:48:00.973] Timer tick, asking Buildbucket for the build status
[09:48:01.056] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:01.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:89:0) after 1m0s
[09:49:01.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:89:0)
[09:49:01.113] Timer tick, asking Buildbucket for the build status
[09:49:01.372] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:01.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:90:0) after 1m0s
[09:50:01.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:90:0)
[09:50:01.351] Timer tick, asking Buildbucket for the build status
[09:50:02.194] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:02.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:91:0) after 1m0s
[09:51:02.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:91:0)
[09:51:02.206] Timer tick, asking Buildbucket for the build status
[09:51:02.345] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:02.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:92:0) after 1m0s
[09:52:02.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:92:0)
[09:52:02.374] Timer tick, asking Buildbucket for the build status
[09:52:02.474] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:02.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:93:0) after 1m0s
[09:53:02.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:93:0)
[09:53:02.493] Timer tick, asking Buildbucket for the build status
[09:53:02.604] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:02.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:94:0) after 1m0s
[09:54:02.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:94:0)
[09:54:02.693] Timer tick, asking Buildbucket for the build status
[09:54:02.802] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:95:0) after 1m0s
[09:55:02.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:95:0)
[09:55:02.838] Timer tick, asking Buildbucket for the build status
[09:55:02.981] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:02.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:96:0) after 1m0s
[09:56:03.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:96:0)
[09:56:03.048] Timer tick, asking Buildbucket for the build status
[09:56:03.437] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:03.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:97:0) after 1m0s
[09:57:03.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:97:0)
[09:57:03.459] Timer tick, asking Buildbucket for the build status
[09:57:03.570] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:03.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:98:0) after 1m0s
[09:58:03.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:98:0)
[09:58:03.550] Timer tick, asking Buildbucket for the build status
[09:58:03.659] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:03.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:99:0) after 1m0s
[09:59:03.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:99:0)
[09:59:03.724] Timer tick, asking Buildbucket for the build status
[09:59:03.902] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:03.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:100:0) after 1m0s
[10:00:04.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:100:0)
[10:00:04.873] Timer tick, asking Buildbucket for the build status
[10:00:05.465] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:05.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:101:0) after 1m0s
[10:01:05.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:101:0)
[10:01:05.536] Timer tick, asking Buildbucket for the build status
[10:01:05.652] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:05.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:102:0) after 1m0s
[10:02:05.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:102:0)
[10:02:05.681] Timer tick, asking Buildbucket for the build status
[10:02:05.823] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:05.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:103:0) after 1m0s
[10:03:05.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:103:0)
[10:03:05.847] Timer tick, asking Buildbucket for the build status
[10:03:06.148] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:06.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:104:0) after 1m0s
[10:04:06.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:104:0)
[10:04:06.173] Timer tick, asking Buildbucket for the build status
[10:04:06.706] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:06.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:105:0) after 1m0s
[10:05:06.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:105:0)
[10:05:06.760] Timer tick, asking Buildbucket for the build status
[10:05:07.843] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:07.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:106:0) after 1m0s
[10:06:07.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:106:0)
[10:06:07.905] Timer tick, asking Buildbucket for the build status
[10:06:08.027] Build 8865573055526431936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:08.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031003117930605552:107:0) after 1m0s
[10:07:06.575] Received PubSub notification, asking Buildbucket for the build status
[10:07:06.939] Build 8865573055526431936: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:07:06.939] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603534025072726",
"created_by": "project:chromium",
"created_ts": "1603527698929012",
"failure_reason": "BUILD_FAILURE",
"id": "8865573055526431936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\"}, \"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\": \"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\", \"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-53-z7d7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42227, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"8180c71a3552a44a91e7d5eaed8a5eecdc758bb7\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\", \"got_revision_cp\": \"refs/heads/master@{#820550}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"3065e6ef3016a906d1db82e1d4322cbd53d43247\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32481}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41cba3cd26b3ac4b29dee328d153e3b21e3e5734\", \"swarm_hashes_refs/heads/master(at){#820550}_without_patch\": {\"absl_hardening_tests\": \"688545d994f6532d7f0b85189d8f075c04666cf9\", \"accessibility_unittests\": \"7b81131be03d6270f77edaba7716931a10daa0ba\", \"angle_unittests\": \"edf535c474337c6c4dfe3adea646df621b9c5ee1\", \"app_list_unittests\": \"b6ce93c090c94ad6d5559a3cbfb8f25ffe9a5356\", \"app_shell_unittests\": \"ec7f459c176d735344a59ae0a527aa5fd0f7c337\", \"ash_unittests\": \"7658cfe003d54d94b458408ab42ca3f768bfedc4\", \"aura_unittests\": \"224b089a269d7b4e2f576f0352e6ec8121e0d149\", \"base_unittests\": \"56c4bc2c66e6b7472c6d21ff121a76d0133e2d5c\", \"base_util_unittests\": \"663d99147321ca41b0dc4bdd5e9016d09cd69722\", \"blink_common_unittests\": \"3ee3a41a0532f4220d235580bfdf9ca1165bf084\", \"blink_fuzzer_unittests\": \"322d56ff9bed3b2cffc67f940c6fb2db766ab33b\", \"blink_heap_unittests\": \"daf1ced4536497d9b8ef7d1c4af1d61600af1b08\", \"blink_platform_unittests\": \"defaa755bbd647eb55aadf541f158401430218d5\", \"blink_unittests\": \"8e504e473b9f33f0fe59d136014784ac1c6c4c5a\", \"boringssl_crypto_tests\": \"5623290ab35bb3a9e56d9c02ef041d8399cb2408\", \"boringssl_ssl_tests\": \"890ac51bfa579d02ed27dbcd8425714c0af2ed42\", \"browser_tests\": \"b3e2738c8ea81713df80c65fe43d01f072f7d8e6\", \"capture_unittests\": \"98b4f2bb25a8c4e9f2949e584e260481b2237e87\", \"cast_unittests\": \"c238dbb0b0aa74b9a687cd934f0f60aa815cfd5f\", \"cc_unittests\": \"c560421f374cbffedef8397f4b5b7be0e40ff987\", \"chrome_app_unittests\": \"573e25f35f7d0f52a0e52370ca47728c349b6c33\", \"chromedriver_unittests\": \"f06fee1da7ee35586fff70a0f04f9f127a2812c5\", \"chromeos_components_unittests\": \"5d89be04e53789f532386571f736e0b8a3002286\", \"chromeos_unittests\": \"dd0a4f7ce51b75e109e07a6fbffc68db44172ee7\", \"components_browsertests\": \"eb1fa74b6ad1ba0fb8cd78710de5ff9c48cc7645\", \"components_unittests\": \"001bde2c893687530e6b2170426a957fefb10f47\", \"compositor_unittests\": \"7a92de534a9e78bfa4c5aa491649a1c740fd8afa\", \"content_browsertests\": \"ee6097f2791d581d585bd2853cdec0d7a9bcb857\", \"content_unittests\": \"b7038cef2ad75fe20a2d256482070bc1e03daaa4\", \"crypto_unittests\": \"1b5dd23cfac7a6653dad131544295ff13eba92c5\", \"dbus_unittests\": \"8eb7d227c09c9fa029fee5cb948fe3cca71708d8\", \"device_unittests\": \"bc222ceff9e1a227ac8394ecd71366c80e9ba914\", \"display_unittests\": \"f0a32454d726d35a02bdfad8a80928abe0fae772\", \"events_unittests\": \"46b8ec172ebca08b06f05c4db01b27d40d267b49\", \"exo_unittests\": \"7095183ebf2c577974fa7c123e4c7617848a94f4\", \"extensions_browsertests\": \"e0fdae2a1d57395899e4b1a13b531fb8058bd07d\", \"extensions_unittests\": \"060741d03e62844aa2efd1e6763deac93bf5d3ba\", \"filesystem_service_unittests\": \"110cea0c2c474c12e8804765745c3297684ec362\", \"gcm_unit_tests\": \"e3fdee2867e650f1329e2f610ecaee59e2a4b75f\", \"gfx_unittests\": \"84d04029a30134561b18428cc4afafda0513e8f8\", \"gin_unittests\": \"6b5f99ec5703e0e7e7dd1e745326042351302b7b\", \"gl_unittests_ozone\": \"a8aa909d9b28726c4276810544befbc5e334ac98\", \"google_apis_unittests\": \"3755fdba12a83b5e35a50ea12362ab3b38dc9498\", \"gpu_unittests\": \"d70519be5a8c3c0072811fd221a0422dc563e7da\", \"gwp_asan_unittests\": \"48e4085ea948aef88fec7f62fb96e8d5e9420e29\", \"interactive_ui_tests\": \"ef7522a76f39d125aea97b81d8f33c58f2f18ac3\", \"ipc_tests\": \"3d79c078491eee4c48ed631d4fd4154808480700\", \"jingle_unittests\": \"450528fc7493d85aa692e4cd7cadcdc3bff3dcff\", \"keyboard_unittests\": \"c768438650a224f0ea55b756eec20b2825f96f15\", \"latency_unittests\": \"b62ec4807a9414ff9d979f0ab3cad4b862d321e2\", \"libjingle_xmpp_unittests\": \"1266a0eede82b2671dd66a5417c7e1c8653b8c09\", \"media_blink_unittests\": \"f96996f601dd5ad44007c717d7961c6044323b85\", \"media_unittests\": \"356d7ffc30537a955f864511ad2d0b3aa90fff9a\", \"message_center_unittests\": \"5f1c6085319b529a1d6206b8b6e292455722e8b8\", \"midi_unittests\": \"dab0b1ccfe5b85065c27d38555fb7e75352acd3d\", \"mojo_core_unittests\": \"b52cc33e904567a6e6a8428deb0b4a57f2a29a7b\", \"mojo_unittests\": \"414e626fef013764dfb8af934fe89c19345f8561\", \"nacl_helper_nonsfi_unittests\": \"66cb5a98a4c30875ec3e22118c472788989ba1c0\", \"nacl_loader_unittests\": \"124868265086d9d0badd950eea2b954b176e7a0d\", \"native_theme_unittests\": \"368549d8c65077ea7b1bf3cdfb1167d13da124f4\", \"net_unittests\": \"47f716b67dbb48bf6ddd412178b61a8359e7f5dc\", \"ozone_gl_unittests\": \"81e3bbcbdf6b8afe801c192dbb98e43326a8b3f1\", \"ozone_unittests\": \"59b7f13976600a0b4c9aa0b7b2a5218d8030bcb7\", \"ozone_x11_unittests\": \"7eafd913791e78c4e906b1e7a6db63b0d9e48e4b\", \"pdf_unittests\": \"b9f877650b0e1bc68b00a26933b6d99c8adcd15b\", \"perfetto_unittests\": \"650ae8effb6795b98a4f3d8b73dc4e60efa21f83\", \"ppapi_unittests\": \"af7128809f7abb026d3d65a343e015d184265966\", \"printing_unittests\": \"c6a23547bd28750bae7fd37928fcacd5409a9fb8\", \"remoting_unittests\": \"ad6ed245eb1c51328f2a0decb0dbd835f0d24eb1\", \"sandbox_linux_unittests\": \"fea96530565e0495689ed51085234f0f4494f882\", \"service_manager_unittests\": \"ed54c6918a4b387b59549a43cf83f84fa33ad976\", \"services_unittests\": \"7fe828634e06459953789ba8cea273117b5208ff\", \"shell_dialogs_unittests\": \"7cc006f3414cc9d53ec5d6c419a775cadc3000d2\", \"skia_unittests\": \"6c1db6e3f6ba983e6023522a6570f1a8253ed5cc\", \"snapshot_unittests\": \"6d0c7c6fb6ad7acbc8bd3d6de3027c79ab2ef030\", \"sql_unittests\": \"7ff19f23e7da379c1e49c06030f603fe8169a7d3\", \"storage_unittests\": \"31e8e2481fafc1e65673241eb4f384a6a10df4cd\", \"sync_integration_tests\": \"9bf5085578a21af7fb38fcd344717b7d6c5b6bb3\", \"traffic_annotation_auditor_unittests\": \"b524696d432752955843976049f7393a8b279415\", \"ui_base_unittests\": \"766d147b7fc6562780e13d09e0ee09a6a39482b1\", \"ui_chromeos_unittests\": \"cd05c50443559671b8b16c10f8cf9fd79c99a2e4\", \"ui_touch_selection_unittests\": \"a41f379eec6a84125e51bc88a3f2e45e812545bd\", \"unit_tests\": \"0f9c776ce00eb8b627cef57320323fa803839a1f\", \"url_unittests\": \"ac74ef8e2bbb2e907ec44d67b2c5203cb61977a7\", \"usage_time_limit_unittests\": \"bc8678fde5f51ef189cc7dffad12397e2cb4445f\", \"views_unittests\": \"7d5af9387e1ad11ae6ef311b05fcaafc086c8aed\", \"viz_unittests\": \"3023c80b084450d0670cbb97be69068a07ea6803\", \"wayland_client_perftests\": \"eccac7dc06d3927736bb65c382ffd9019e3b11d3\", \"wm_unittests\": \"ee3c90dcf9e32d4977458212f36e95a16d3ef217\", \"wtf_unittests\": \"7ba72d5e102f6010b2b180fdb55c2ad5b3577898\", \"zlib_unittests\": \"6ca90be974f4fe5e8a10d9ae601dea0e2dff5ec2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_544ce64b59aa2a62f93b2d1058d8c643105c17650fa0dc2da4c94ddccafb95f6_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8f1f1a811acb8ac7cee91f001f34cb98db55e889611ed2a31914ce7c854c1541_v2\", \"builder_a50faa98e00aefb88600aa330f70664f9b4b54ee0ff51cb8b03a7f0a0b737b38_v2\", \"builder_b5e41ce0b01ce81870a1097ddb1343f2a3924630f4d3bb9f1a0eaa4d6a5abbed_v2\", \"builder_ea6f1332cf6753d9a1f310f0d5b8921378658a09424260c337767cc054aa30d1_v2\", \"builder_ebb06fb409ef3255b3561ad6bc0df7706a15849e85ba50b708bdfb917530d411_v2\", \"builder_f11fafa89e130b21aaaf89babe10260b305cabf64cf9dbedf786af2782bba9d2_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-53-z7d7\"], \"image\": [\"chrome-xenial-20101800-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-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603529256563036",
"status": "COMPLETED",
"status_changed_ts": "1603529256869445",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42227",
"builder:linux-chromeos-rel",
"buildset:commit/git/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41cba3cd26b3ac4b29dee328d153e3b21e3e5734",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031003117930605552",
"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/8865573055526431936/+/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:4f7284766ef59c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603534026112932",
"url": "https://ci.chromium.org/b/8865573055526431936",
"utcnow_ts": "1603534026913853"
}
}
[10:07:06.939] Invocation finished in 1h45m29.687531779s with status FAILED