[16:22:59.499] New invocation is queued and will start shortly
[16:23:01.046] Starting the invocation (attempt 1)
[16:23:01.075] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:23:01.075] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:23:01.075] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018017466519901984",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"62676b0a8896587d0c882ad2b372a34bff23ee4f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018017466519901984",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"gitiles_ref:refs/heads/master"
]
}
[16:23:01.957] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615911781215619",
"id": "8852587404058482736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"62676b0a8896587d0c882ad2b372a34bff23ee4f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615911781776401",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46190",
"builder:linux-chromeos-rel",
"buildset:commit/git/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018017466519901984",
"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/8852587404058482736/+/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": "1615911781776518",
"url": "https://ci.chromium.org/b/8852587404058482736",
"utcnow_ts": "1615911781945938"
}
}
[16:23:01.957] Task URL: https://ci.chromium.org/b/8852587404058482736
[16:23:01.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:2:0) after 1m0s
[16:24:01.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:2:0)
[16:24:01.984] Timer tick, asking Buildbucket for the build status
[16:24:02.112] Build 8852587404058482736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:02.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:3:0) after 1m0s
[16:25:02.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:3:0)
[16:25:02.188] Timer tick, asking Buildbucket for the build status
[16:25:02.262] Build 8852587404058482736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:02.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:4:0) after 1m0s
[16:26:02.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:4:0)
[16:26:02.546] Timer tick, asking Buildbucket for the build status
[16:26:02.799] Build 8852587404058482736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:02.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:5:0) after 1m0s
[16:27:03.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:5:0)
[16:27:03.092] Timer tick, asking Buildbucket for the build status
[16:27:03.185] Build 8852587404058482736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:03.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:6:0) after 1m0s
[16:28:03.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:6:0)
[16:28:03.364] Timer tick, asking Buildbucket for the build status
[16:28:03.439] Build 8852587404058482736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:03.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:7:0) after 1m0s
[16:28:09.638] Received PubSub notification, asking Buildbucket for the build status
[16:28:09.702] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:03.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:7:0)
[16:29:03.483] Timer tick, asking Buildbucket for the build status
[16:29:03.632] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:03.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:9:0) after 1m0s
[16:30:03.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:9:0)
[16:30:03.696] Timer tick, asking Buildbucket for the build status
[16:30:03.961] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:03.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:10:0) after 1m0s
[16:31:04.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:10:0)
[16:31:04.112] Timer tick, asking Buildbucket for the build status
[16:31:04.337] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:04.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:11:0) after 1m0s
[16:32:04.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:11:0)
[16:32:04.397] Timer tick, asking Buildbucket for the build status
[16:32:04.455] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:04.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:12:0) after 1m0s
[16:33:04.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:12:0)
[16:33:04.514] Timer tick, asking Buildbucket for the build status
[16:33:04.614] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:04.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:13:0) after 1m0s
[16:34:04.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:13:0)
[16:34:04.654] Timer tick, asking Buildbucket for the build status
[16:34:04.733] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:04.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:14:0) after 1m0s
[16:35:04.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:14:0)
[16:35:04.813] Timer tick, asking Buildbucket for the build status
[16:35:04.883] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:04.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:15:0) after 1m0s
[16:36:04.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:15:0)
[16:36:04.940] Timer tick, asking Buildbucket for the build status
[16:36:05.119] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:05.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:16:0) after 1m0s
[16:37:05.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:16:0)
[16:37:05.212] Timer tick, asking Buildbucket for the build status
[16:37:05.298] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:05.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:17:0) after 1m0s
[16:38:05.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:17:0)
[16:38:05.382] Timer tick, asking Buildbucket for the build status
[16:38:05.651] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:05.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:18:0) after 1m0s
[16:39:05.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:18:0)
[16:39:05.658] Timer tick, asking Buildbucket for the build status
[16:39:05.756] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:05.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:19:0) after 1m0s
[16:40:05.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:19:0)
[16:40:05.774] Timer tick, asking Buildbucket for the build status
[16:40:05.848] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:05.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:20:0) after 1m0s
[16:41:05.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:20:0)
[16:41:05.843] Timer tick, asking Buildbucket for the build status
[16:41:06.150] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:06.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:21:0) after 1m0s
[16:42:06.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:21:0)
[16:42:06.145] Timer tick, asking Buildbucket for the build status
[16:42:06.214] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:06.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:22:0) after 1m0s
[16:43:06.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:22:0)
[16:43:06.211] Timer tick, asking Buildbucket for the build status
[16:43:06.360] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:06.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:23:0) after 1m0s
[16:44:06.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:23:0)
[16:44:06.426] Timer tick, asking Buildbucket for the build status
[16:44:06.553] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:06.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:24:0) after 1m0s
[16:45:06.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:24:0)
[16:45:06.578] Timer tick, asking Buildbucket for the build status
[16:45:07.133] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:07.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:25:0) after 1m0s
[16:46:07.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:25:0)
[16:46:07.218] Timer tick, asking Buildbucket for the build status
[16:46:10.987] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:10.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:26:0) after 1m0s
[16:47:11.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:26:0)
[16:47:11.040] Timer tick, asking Buildbucket for the build status
[16:47:11.138] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:11.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:27:0) after 1m0s
[16:48:11.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:27:0)
[16:48:11.181] Timer tick, asking Buildbucket for the build status
[16:48:11.283] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:11.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:28:0) after 1m0s
[16:49:11.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:28:0)
[16:49:11.385] Timer tick, asking Buildbucket for the build status
[16:49:11.471] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:11.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:29:0) after 1m0s
[16:50:11.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:29:0)
[16:50:11.506] Timer tick, asking Buildbucket for the build status
[16:50:11.777] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:11.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:30:0) after 1m0s
[16:51:11.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:30:0)
[16:51:11.795] Timer tick, asking Buildbucket for the build status
[16:51:11.880] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:11.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:31:0) after 1m0s
[16:52:11.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:31:0)
[16:52:11.947] Timer tick, asking Buildbucket for the build status
[16:52:12.107] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:12.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:32:0) after 1m0s
[16:53:12.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:32:0)
[16:53:12.129] Timer tick, asking Buildbucket for the build status
[16:53:12.655] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:12.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:33:0) after 1m0s
[16:54:12.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:33:0)
[16:54:12.728] Timer tick, asking Buildbucket for the build status
[16:54:12.820] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:12.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:34:0) after 1m0s
[16:55:12.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:34:0)
[16:55:12.860] Timer tick, asking Buildbucket for the build status
[16:55:14.144] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:14.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:35:0) after 1m0s
[16:56:14.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:35:0)
[16:56:14.307] Timer tick, asking Buildbucket for the build status
[16:56:14.378] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:14.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:36:0) after 1m0s
[16:57:14.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:36:0)
[16:57:14.419] Timer tick, asking Buildbucket for the build status
[16:57:14.534] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:14.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:37:0) after 1m0s
[16:58:14.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:37:0)
[16:58:14.532] Timer tick, asking Buildbucket for the build status
[16:58:15.228] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:15.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:38:0) after 1m0s
[16:59:15.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:38:0)
[16:59:15.302] Timer tick, asking Buildbucket for the build status
[16:59:15.818] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:15.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:39:0) after 1m0s
[17:00:15.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:39:0)
[17:00:15.824] Timer tick, asking Buildbucket for the build status
[17:00:16.115] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:16.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:40:0) after 1m0s
[17:01:16.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:40:0)
[17:01:16.132] Timer tick, asking Buildbucket for the build status
[17:01:16.220] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:16.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:41:0) after 1m0s
[17:02:16.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:41:0)
[17:02:16.289] Timer tick, asking Buildbucket for the build status
[17:02:16.526] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:16.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:42:0) after 1m0s
[17:03:16.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:42:0)
[17:03:16.543] Timer tick, asking Buildbucket for the build status
[17:03:18.270] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:18.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:43:0) after 1m0s
[17:04:18.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:43:0)
[17:04:18.268] Timer tick, asking Buildbucket for the build status
[17:04:18.342] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:18.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:44:0) after 1m0s
[17:05:18.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:44:0)
[17:05:18.431] Timer tick, asking Buildbucket for the build status
[17:05:18.557] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:18.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:45:0) after 1m0s
[17:06:18.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:45:0)
[17:06:18.639] Timer tick, asking Buildbucket for the build status
[17:06:18.715] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:18.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:46:0) after 1m0s
[17:07:18.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:46:0)
[17:07:18.800] Timer tick, asking Buildbucket for the build status
[17:07:19.244] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:19.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:47:0) after 1m0s
[17:08:19.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:47:0)
[17:08:19.568] Timer tick, asking Buildbucket for the build status
[17:08:19.767] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:19.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:48:0) after 1m0s
[17:09:19.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:48:0)
[17:09:19.894] Timer tick, asking Buildbucket for the build status
[17:09:20.185] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:20.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:49:0) after 1m0s
[17:10:20.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:49:0)
[17:10:20.307] Timer tick, asking Buildbucket for the build status
[17:10:20.378] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:20.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:50:0) after 1m0s
[17:11:20.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:50:0)
[17:11:20.393] Timer tick, asking Buildbucket for the build status
[17:11:20.654] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:20.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:51:0) after 1m0s
[17:12:20.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:51:0)
[17:12:20.797] Timer tick, asking Buildbucket for the build status
[17:12:20.980] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:20.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:52:0) after 1m0s
[17:13:21.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:52:0)
[17:13:21.050] Timer tick, asking Buildbucket for the build status
[17:13:21.181] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:21.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:53:0) after 1m0s
[17:14:21.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:53:0)
[17:14:21.204] Timer tick, asking Buildbucket for the build status
[17:14:21.285] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:21.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:54:0) after 1m0s
[17:15:21.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:54:0)
[17:15:21.317] Timer tick, asking Buildbucket for the build status
[17:15:21.426] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:21.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:55:0) after 1m0s
[17:16:21.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:55:0)
[17:16:21.454] Timer tick, asking Buildbucket for the build status
[17:16:21.618] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:21.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:56:0) after 1m0s
[17:17:21.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:56:0)
[17:17:21.636] Timer tick, asking Buildbucket for the build status
[17:17:21.709] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:21.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:57:0) after 1m0s
[17:18:21.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:57:0)
[17:18:21.703] Timer tick, asking Buildbucket for the build status
[17:18:21.876] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:21.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:58:0) after 1m0s
[17:19:21.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:58:0)
[17:19:21.922] Timer tick, asking Buildbucket for the build status
[17:19:22.342] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:22.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:59:0) after 1m0s
[17:20:22.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:59:0)
[17:20:22.426] Timer tick, asking Buildbucket for the build status
[17:20:22.506] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:22.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:60:0) after 1m0s
[17:21:22.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:60:0)
[17:21:22.546] Timer tick, asking Buildbucket for the build status
[17:21:22.607] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:22.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:61:0) after 1m0s
[17:22:22.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:61:0)
[17:22:22.688] Timer tick, asking Buildbucket for the build status
[17:22:22.771] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:22.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:62:0) after 1m0s
[17:23:22.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:62:0)
[17:23:22.916] Timer tick, asking Buildbucket for the build status
[17:23:23.054] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:23.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:63:0) after 1m0s
[17:24:23.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:63:0)
[17:24:23.071] Timer tick, asking Buildbucket for the build status
[17:24:23.405] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:23.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:64:0) after 1m0s
[17:25:23.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:64:0)
[17:25:23.567] Timer tick, asking Buildbucket for the build status
[17:25:23.745] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:23.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:65:0) after 1m0s
[17:26:23.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:65:0)
[17:26:23.980] Timer tick, asking Buildbucket for the build status
[17:26:24.122] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:24.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:66:0) after 1m0s
[17:27:24.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:66:0)
[17:27:24.293] Timer tick, asking Buildbucket for the build status
[17:27:24.687] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:24.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:67:0) after 1m0s
[17:28:25.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:67:0)
[17:28:25.493] Timer tick, asking Buildbucket for the build status
[17:28:25.793] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:25.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:68:0) after 1m0s
[17:29:25.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:68:0)
[17:29:25.836] Timer tick, asking Buildbucket for the build status
[17:29:25.949] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:25.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:69:0) after 1m0s
[17:30:26.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:69:0)
[17:30:26.171] Timer tick, asking Buildbucket for the build status
[17:30:26.294] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:26.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:70:0) after 1m0s
[17:31:26.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:70:0)
[17:31:26.381] Timer tick, asking Buildbucket for the build status
[17:31:26.659] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:26.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:71:0) after 1m0s
[17:32:26.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:71:0)
[17:32:26.866] Timer tick, asking Buildbucket for the build status
[17:32:26.937] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:26.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:72:0) after 1m0s
[17:33:27.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:72:0)
[17:33:27.046] Timer tick, asking Buildbucket for the build status
[17:33:27.231] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:27.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:73:0) after 1m0s
[17:34:27.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:73:0)
[17:34:27.447] Timer tick, asking Buildbucket for the build status
[17:34:27.572] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:27.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:74:0) after 1m0s
[17:35:28.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:74:0)
[17:35:28.188] Timer tick, asking Buildbucket for the build status
[17:35:28.374] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:28.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:75:0) after 1m0s
[17:36:28.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:75:0)
[17:36:28.400] Timer tick, asking Buildbucket for the build status
[17:36:28.473] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:28.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:76:0) after 1m0s
[17:37:28.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:76:0)
[17:37:28.517] Timer tick, asking Buildbucket for the build status
[17:37:28.583] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:28.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:77:0) after 1m0s
[17:38:28.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:77:0)
[17:38:28.671] Timer tick, asking Buildbucket for the build status
[17:38:28.764] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:28.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:78:0) after 1m0s
[17:39:28.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:78:0)
[17:39:28.945] Timer tick, asking Buildbucket for the build status
[17:39:29.053] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:79:0) after 1m0s
[17:40:29.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:79:0)
[17:40:29.079] Timer tick, asking Buildbucket for the build status
[17:40:29.152] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:29.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:80:0) after 1m0s
[17:41:29.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:80:0)
[17:41:29.257] Timer tick, asking Buildbucket for the build status
[17:41:29.334] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:29.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:81:0) after 1m0s
[17:42:29.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:81:0)
[17:42:29.397] Timer tick, asking Buildbucket for the build status
[17:42:29.463] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:29.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:82:0) after 1m0s
[17:43:29.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:82:0)
[17:43:29.509] Timer tick, asking Buildbucket for the build status
[17:43:32.079] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:32.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:83:0) after 1m0s
[17:44:32.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:83:0)
[17:44:32.145] Timer tick, asking Buildbucket for the build status
[17:44:32.229] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:32.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:84:0) after 1m0s
[17:45:32.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:84:0)
[17:45:32.340] Timer tick, asking Buildbucket for the build status
[17:45:32.592] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:32.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:85:0) after 1m0s
[17:46:32.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:85:0)
[17:46:32.694] Timer tick, asking Buildbucket for the build status
[17:46:32.925] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:32.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:86:0) after 1m0s
[17:47:33.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:86:0)
[17:47:33.069] Timer tick, asking Buildbucket for the build status
[17:47:33.173] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:33.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:87:0) after 1m0s
[17:48:33.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:87:0)
[17:48:33.209] Timer tick, asking Buildbucket for the build status
[17:48:34.247] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:34.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:88:0) after 1m0s
[17:49:34.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:88:0)
[17:49:34.280] Timer tick, asking Buildbucket for the build status
[17:49:34.640] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:34.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:89:0) after 1m0s
[17:50:34.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:89:0)
[17:50:34.659] Timer tick, asking Buildbucket for the build status
[17:50:35.300] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:35.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:90:0) after 1m0s
[17:51:35.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:90:0)
[17:51:35.326] Timer tick, asking Buildbucket for the build status
[17:51:35.473] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:35.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:91:0) after 1m0s
[17:52:35.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:91:0)
[17:52:35.447] Timer tick, asking Buildbucket for the build status
[17:52:37.912] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:37.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:92:0) after 1m0s
[17:53:37.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:92:0)
[17:53:37.969] Timer tick, asking Buildbucket for the build status
[17:53:38.078] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:38.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:93:0) after 1m0s
[17:54:38.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:93:0)
[17:54:38.115] Timer tick, asking Buildbucket for the build status
[17:54:38.235] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:38.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:94:0) after 1m0s
[17:55:38.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:94:0)
[17:55:38.275] Timer tick, asking Buildbucket for the build status
[17:55:38.803] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:38.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:95:0) after 1m0s
[17:56:38.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:95:0)
[17:56:38.852] Timer tick, asking Buildbucket for the build status
[17:56:38.968] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:38.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:96:0) after 1m0s
[17:57:38.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:96:0)
[17:57:38.942] Timer tick, asking Buildbucket for the build status
[17:57:39.057] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:39.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:97:0) after 1m0s
[17:58:39.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:97:0)
[17:58:39.077] Timer tick, asking Buildbucket for the build status
[17:58:39.221] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:98:0) after 1m0s
[17:59:39.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:98:0)
[17:59:39.260] Timer tick, asking Buildbucket for the build status
[17:59:39.400] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:39.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:99:0) after 1m0s
[18:00:39.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:99:0)
[18:00:39.387] Timer tick, asking Buildbucket for the build status
[18:00:39.802] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:39.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:100:0) after 1m0s
[18:01:39.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:100:0)
[18:01:39.837] Timer tick, asking Buildbucket for the build status
[18:01:39.952] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:39.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:101:0) after 1m0s
[18:02:40.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:101:0)
[18:02:40.002] Timer tick, asking Buildbucket for the build status
[18:02:40.086] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:40.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:102:0) after 1m0s
[18:03:40.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:102:0)
[18:03:40.111] Timer tick, asking Buildbucket for the build status
[18:03:40.242] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:103:0) after 1m0s
[18:04:40.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:103:0)
[18:04:40.268] Timer tick, asking Buildbucket for the build status
[18:04:40.363] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:40.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:104:0) after 1m0s
[18:05:40.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:104:0)
[18:05:40.403] Timer tick, asking Buildbucket for the build status
[18:05:40.548] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:40.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:105:0) after 1m0s
[18:06:40.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:105:0)
[18:06:40.532] Timer tick, asking Buildbucket for the build status
[18:06:40.611] Build 8852587404058482736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:40.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018017466519901984:106:0) after 1m0s
[18:07:14.505] Received PubSub notification, asking Buildbucket for the build status
[18:07:17.682] Build 8852587404058482736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:07:17.682] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615918033761788",
"created_by": "project:chromium",
"created_ts": "1615911781215619",
"failure_reason": "BUILD_FAILURE",
"id": "8852587404058482736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"62676b0a8896587d0c882ad2b372a34bff23ee4f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AccessibilityExtensionCursorsTest.SingleDocSelection\"}, \"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\": \"62676b0a8896587d0c882ad2b372a34bff23ee4f\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-166-3f40\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46190, \"got_angle_revision\": \"6936ee8064dda1221c2358c9eae42e671fac0023\", \"got_dawn_revision\": \"54586e61210cfdfc9835f81b57c794f4ac19356b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"62676b0a8896587d0c882ad2b372a34bff23ee4f\", \"got_revision_cp\": \"refs/heads/master@{#863312}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"929acfd81ac536f1b7d5699427a01e1238efabd4\", \"got_v8_revision_cp\": \"refs/heads/9.1.133@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"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\": \"62676b0a8896587d0c882ad2b372a34bff23ee4f\", \"swarm_hashes_refs/heads/master(at){#863312}_without_patch\": {\"absl_hardening_tests\": \"09a75f857b8735ea1ead1e094b98d0fe441d7c96\", \"accessibility_unittests\": \"3440204003bfe700e44f01b28a59ee5ea906d6a9\", \"angle_unittests\": \"1f5a665d210f21328dd4e0616e2355a5c6b0db69\", \"app_list_unittests\": \"ede970b2786da6a0eace9f729753c51589e18285\", \"app_shell_unittests\": \"fc19b72c9171b2a6a24b39db04b781c0d7e9ef16\", \"ash_unittests\": \"0ee7a6274c73fe6f167de4bc651525092ffcb023\", \"aura_unittests\": \"cb7ba56c89991362016f48704943314fbe5d61eb\", \"base_unittests\": \"5549c022c7151d675cb14f1070f7d4233886a119\", \"base_util_unittests\": \"a6d8a2f75a9dbc361df44b882d3e1c256e7614c1\", \"blink_common_unittests\": \"69b633ccf398712c66ed838515334f5c42ab18e6\", \"blink_fuzzer_unittests\": \"c022d37914307f5e527e32633c7fe03156fec4e7\", \"blink_heap_unittests\": \"7cb558578807cf67f22e89c78cfde292fe3a52a8\", \"blink_platform_unittests\": \"52c85244d6b4216b1e627af59b1b99149114f618\", \"blink_unittests\": \"fbe4d2250bb7378954a82ba31affe2e4172d8575\", \"boringssl_crypto_tests\": \"8e6103e98e37508c7e25331a557b96b71fe2f970\", \"boringssl_ssl_tests\": \"6633fcfaea23a70d368f4688429153a4b152d6e0\", \"browser_tests\": \"31ddaec0704000b28c05949bb5c5eb69c79c6589\", \"capture_unittests\": \"688af2011fa1aba4d809514f9e4a5ec4054b802f\", \"cast_unittests\": \"c18e451a34799d22d9d50ad36f588182d9167bb7\", \"cc_unittests\": \"3c7871dea52933c3893a6c7153e9b5b47e387341\", \"chrome_app_unittests\": \"21c942390bd4e3aadc7af2e1f06445bf54d57239\", \"chromedriver_unittests\": \"6c0e2b1e9f94efd10ebda094d0c5775542ba9c7b\", \"chromeos_components_unittests\": \"5b4f3f9a69fb786a6577e995eacd0010d5561d99\", \"chromeos_unittests\": \"66352d1efbaf7edd7cd54c4468d1c1d7a7e01ff4\", \"color_unittests\": \"66d7da133994824d9467b0a85f633aa096d4a8aa\", \"components_browsertests\": \"96fc4ab075272772095e73ee5d59e63c10b187fa\", \"components_unittests\": \"025ca1d7d5555c358807e6be54be67904e0df719\", \"compositor_unittests\": \"e7f78a9cc41b5a6a5e5c2990553866f29fb007d4\", \"content_browsertests\": \"fb8e544a2daa9e798902af01096faccb2a1f37a9\", \"content_unittests\": \"2ee2554bff503f87b1502c581b019fa3e3664dc2\", \"crashpad_tests\": \"08e09b5aa69b77ab487bc5cf7b2bdb2859437003\", \"crypto_unittests\": \"9a6585219333c000eeeac2f00980d2d38383c1d6\", \"dbus_unittests\": \"617457838f0c26d698c0f3c6be80678df6d92d24\", \"device_unittests\": \"f4a7bc181d0d1fba909ae0d96c1869718b7166e1\", \"display_unittests\": \"422762a874dfce51ccccb2995d32cff2012ffc58\", \"events_unittests\": \"1c24f79453db2ee7d9a51b6d4e07ed42c0b48627\", \"exo_unittests\": \"b3ca146b39d2bca818ae67a00c99522b4ba04ee9\", \"extensions_browsertests\": \"63951bd1ed427e4ccf251e596f79663762a7366d\", \"extensions_unittests\": \"dc293843ac30da6683291d1b4489e0d98c73ef74\", \"filesystem_service_unittests\": \"7fe4df8dbc8a5e9751da74b8fb82059d62e01a56\", \"gcm_unit_tests\": \"f6484d27652e9aad4cc8fe2e33b9d4066d4efb1a\", \"gfx_unittests\": \"15f51fe7d44fc2058d4c92a47e960a8ec94fac1c\", \"gin_unittests\": \"14c94775839a7f129c45a41614fcd1c2af14d2c7\", \"gl_unittests_ozone\": \"e544019bbb34c086b67385dc61ede473cfe611cb\", \"google_apis_unittests\": \"44728757bdeadce61843a02d5b3b10e8b2da8aa9\", \"gpu_unittests\": \"c4806201fa267f870dae9257ee0aa4bb2a80001a\", \"gwp_asan_unittests\": \"4866913d0533c9c4341d8e5aa374d6ca691c297e\", \"interactive_ui_tests\": \"3dfb3f7e829043789626ee088ed8102c9521ec33\", \"ipc_tests\": \"bd10cedc7609f39fd26413228a097f6982b49c81\", \"jingle_unittests\": \"3ccf4912ee4670e07469a55fd0cc06be1234a888\", \"keyboard_unittests\": \"2feed09fad4713dc1c362e4b20f159cebeab4f75\", \"latency_unittests\": \"32ba0bdf038964ac40253b3e7e14f6520de21534\", \"libjingle_xmpp_unittests\": \"1bf44bd3c0f02b249f74df30cc4749bd0a465461\", \"liburlpattern_unittests\": \"e03cedeb0556caf571bc126cde5ddae7401926a6\", \"media_blink_unittests\": \"260ae63ca418ae4b845041f10a714d080d0ba814\", \"media_unittests\": \"87ed12832f8f210960349ae3b631284226ddab76\", \"message_center_unittests\": \"015d1f0fef7024b4648a850cf33b2d58da7123f3\", \"midi_unittests\": \"d53c09b55eb748a765a743159c8f3a96ce04a06b\", \"mojo_core_unittests\": \"2c8933abe7518b777055d383c65dac71cebc48c2\", \"mojo_unittests\": \"58a4e209cfcb9f49cfbf560c17aa8c5b935d0082\", \"nacl_helper_nonsfi_unittests\": \"36364424047c4c83da458f5e29b89504d2c35209\", \"nacl_loader_unittests\": \"f370a7bfa0ade633ea64fa5af60f56efe5f0e8bd\", \"native_theme_unittests\": \"c095c1d474e52623cb70c49a945ffe21b3a49ed9\", \"net_unittests\": \"7ae22e2709952c30a9b7fc0ba502b2d01c74c6da\", \"ozone_gl_unittests\": \"5e6cac9b509a3331b1dfad6860848aaf6bbce0f8\", \"ozone_unittests\": \"0443192dc3fdd69ff8e28a28a7f4d95992034692\", \"ozone_x11_unittests\": \"6ddd5ec3c103fa8ad49ff9ae42c31b17afc86432\", \"pdf_unittests\": \"fbd9244c8c33dd54bd7306ef39c5d3f90e5cbcfe\", \"perfetto_unittests\": \"e5bdf92ac8335a9942238310e6e48e769f16770a\", \"ppapi_unittests\": \"b53681fd2d69d22bb9653fcf7c95fb3f39aee0e1\", \"printing_unittests\": \"16a96323cffc2a6b392e81983e3036d0a61bd9b3\", \"remoting_unittests\": \"19881f0b2bc5833f482e8345b483172b4c0cf642\", \"sandbox_linux_unittests\": \"7cdabaf719dd09883c19c13f0e047fa5f8cda040\", \"service_manager_unittests\": \"95ea0e3ec448e87ad18d179ba6f15f3a9204dc70\", \"services_unittests\": \"197e30eca720d164a2af57a4573b1d95aba395c4\", \"shell_dialogs_unittests\": \"45a9fefee425db76a44e155450ef5f9c7e961356\", \"shell_encryption_unittests\": \"bd949448cce9b2539bf06e52e8cfac58facf480c\", \"skia_unittests\": \"46ffc0c1a0527ac3b58e1dd0d2251cc7dba17f4a\", \"snapshot_unittests\": \"9036475072b34824a30e5d06d94f9572a755be31\", \"sql_unittests\": \"d984433c79e2b4540254e1193c19928d5dcb6f9c\", \"storage_unittests\": \"771b86ef64d9e4023cdb48c4dc066e1ff30850a1\", \"sync_integration_tests\": \"36699ecc4b32fb27cfadceb5d1661a5570ac826a\", \"traffic_annotation_auditor_unittests\": \"3b0c5ebd6cd4b03c4a8911bcd27a98b9b79b88d4\", \"ui_base_unittests\": \"89fb1371884478db5bec9a31a58c8903646f769e\", \"ui_chromeos_unittests\": \"bf3934a511279e59d911f729f5b4202b2da82a25\", \"ui_touch_selection_unittests\": \"b2ff4fa6d0c5bae9712b446a8cd41eae95ca586d\", \"unit_tests\": \"9c5e7b77af0ca59ec6967a0657a6efea501499f7\", \"url_unittests\": \"628ae595fda6611ec88f42ceb69423f0f8fa7a84\", \"usage_time_limit_unittests\": \"d63daf9bb0765db039a347fabf2bf6e98ee5bf3b\", \"views_unittests\": \"94d1999993ebd90ba982972e0e1d9846f8a64983\", \"viz_unittests\": \"684f8181de41f08f6070220c22c145bcf77a71d9\", \"wayland_client_perftests\": \"af844220ac97bd4092e75b4f7c94f4be49bb90cb\", \"wayland_client_tests\": \"d4ec11fde7b76bbcebe57805432201877954cda3\", \"wm_unittests\": \"15d4c1d2c3e818d0f83ec21ad820796105dcbf96\", \"wtf_unittests\": \"49d8bc48086fd65eb37860a865a3942be9d3a55b\", \"zlib_unittests\": \"d4b8749b60f3e27002bc5806271d940afde0bf5f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_304607b978b8f4bcd63bac0f543c3a69f6b4c7cf202dcd2d3c5b8d4a70be201e_v2\", \"builder_61cf7edf32cfa6f409296179bf0eb529cec28454dd3732e2fae2e14176388844_v2\", \"builder_6440ef9dedd0dc3b34dcb22ab34f3415d4cc49eb5cc8c1aeab1a9757364e9da5_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-166-3f40\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AccessibilityExtensionCursorsTest.SingleDocSelection\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615912089023886",
"status": "COMPLETED",
"status_changed_ts": "1615912089220964",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46190",
"builder:linux-chromeos-rel",
"buildset:commit/git/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/62676b0a8896587d0c882ad2b372a34bff23ee4f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018017466519901984",
"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/8852587404058482736/+/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:5254aa8ef2d01710",
"user_agent:luci-scheduler"
],
"updated_ts": "1615918033762211",
"url": "https://ci.chromium.org/b/8852587404058482736",
"utcnow_ts": "1615918037672890"
}
}
[18:07:17.682] Invocation finished in 1h44m18.196959385s with status FAILED