[20:28:05.534] New invocation is queued and will start shortly
[20:28:06.831] Starting the invocation (attempt 1)
[20:28:06.872] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:28:06.872] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:28:06.872] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029689054792278352",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9b7c3260313fed9f249de0c54918cd0dd0a107af\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029689054792278352",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"gitiles_ref:refs/heads/master"
]
}
[20:28:07.856] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604780886955469",
"id": "8864258992638345968",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b7c3260313fed9f249de0c54918cd0dd0a107af\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604780887547713",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42675",
"builder:linux-chromeos-rel",
"buildset:commit/git/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029689054792278352",
"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/8864258992638345968/+/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": "1604780887547871",
"url": "https://ci.chromium.org/b/8864258992638345968",
"utcnow_ts": "1604780887841806"
}
}
[20:28:07.856] Task URL: https://ci.chromium.org/b/8864258992638345968
[20:28:07.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:2:0) after 1m0s
[20:29:07.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:2:0)
[20:29:07.914] Timer tick, asking Buildbucket for the build status
[20:29:08.006] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:08.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:3:0) after 1m0s
[20:30:08.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:3:0)
[20:30:08.046] Timer tick, asking Buildbucket for the build status
[20:30:08.121] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:08.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:4:0) after 1m0s
[20:31:08.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:4:0)
[20:31:08.223] Timer tick, asking Buildbucket for the build status
[20:31:08.700] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:08.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:5:0) after 1m0s
[20:32:09.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:5:0)
[20:32:09.105] Timer tick, asking Buildbucket for the build status
[20:32:09.186] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:09.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:6:0) after 1m0s
[20:33:09.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:6:0)
[20:33:09.228] Timer tick, asking Buildbucket for the build status
[20:33:09.415] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:09.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:7:0) after 1m0s
[20:34:09.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:7:0)
[20:34:09.484] Timer tick, asking Buildbucket for the build status
[20:34:09.740] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:09.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:8:0) after 1m0s
[20:35:09.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:8:0)
[20:35:09.843] Timer tick, asking Buildbucket for the build status
[20:35:09.923] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:09.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:9:0) after 1m0s
[20:36:10.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:9:0)
[20:36:10.075] Timer tick, asking Buildbucket for the build status
[20:36:10.152] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:10.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:10:0) after 1m0s
[20:37:10.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:10:0)
[20:37:10.396] Timer tick, asking Buildbucket for the build status
[20:37:10.488] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:10.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:11:0) after 1m0s
[20:38:10.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:11:0)
[20:38:10.553] Timer tick, asking Buildbucket for the build status
[20:38:10.687] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:10.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:12:0) after 1m0s
[20:39:10.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:12:0)
[20:39:10.751] Timer tick, asking Buildbucket for the build status
[20:39:10.820] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:10.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:13:0) after 1m0s
[20:40:10.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:13:0)
[20:40:10.866] Timer tick, asking Buildbucket for the build status
[20:40:11.168] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:11.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:14:0) after 1m0s
[20:41:11.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:14:0)
[20:41:11.216] Timer tick, asking Buildbucket for the build status
[20:41:11.284] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:11.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:15:0) after 1m0s
[20:42:11.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:15:0)
[20:42:11.366] Timer tick, asking Buildbucket for the build status
[20:42:11.578] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:16:0) after 1m0s
[20:43:11.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:16:0)
[20:43:11.634] Timer tick, asking Buildbucket for the build status
[20:43:11.750] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:11.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:17:0) after 1m0s
[20:44:11.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:17:0)
[20:44:11.732] Timer tick, asking Buildbucket for the build status
[20:44:11.803] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:11.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:18:0) after 1m0s
[20:45:11.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:18:0)
[20:45:11.878] Timer tick, asking Buildbucket for the build status
[20:45:11.962] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:11.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:19:0) after 1m0s
[20:46:12.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:19:0)
[20:46:12.037] Timer tick, asking Buildbucket for the build status
[20:46:12.114] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:12.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:20:0) after 1m0s
[20:47:12.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:20:0)
[20:47:12.240] Timer tick, asking Buildbucket for the build status
[20:47:12.346] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:12.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:21:0) after 1m0s
[20:48:12.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:21:0)
[20:48:12.377] Timer tick, asking Buildbucket for the build status
[20:48:13.241] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:13.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:22:0) after 1m0s
[20:49:13.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:22:0)
[20:49:13.333] Timer tick, asking Buildbucket for the build status
[20:49:13.407] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:13.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:23:0) after 1m0s
[20:50:13.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:23:0)
[20:50:13.502] Timer tick, asking Buildbucket for the build status
[20:50:13.769] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:13.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:24:0) after 1m0s
[20:51:13.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:24:0)
[20:51:13.783] Timer tick, asking Buildbucket for the build status
[20:51:13.873] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:13.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:25:0) after 1m0s
[20:52:13.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:25:0)
[20:52:13.989] Timer tick, asking Buildbucket for the build status
[20:52:14.147] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:14.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:26:0) after 1m0s
[20:53:14.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:26:0)
[20:53:14.225] Timer tick, asking Buildbucket for the build status
[20:53:14.331] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:14.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:27:0) after 1m0s
[20:54:14.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:27:0)
[20:54:14.499] Timer tick, asking Buildbucket for the build status
[20:54:14.566] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:14.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:28:0) after 1m0s
[20:55:14.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:28:0)
[20:55:14.702] Timer tick, asking Buildbucket for the build status
[20:55:14.793] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:14.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:29:0) after 1m0s
[20:56:19.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:29:0)
[20:56:19.562] Timer tick, asking Buildbucket for the build status
[20:56:19.631] Build 8864258992638345968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:19.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:30:0) after 1m0s
[20:56:55.470] Received PubSub notification, asking Buildbucket for the build status
[20:56:55.603] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:19.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:30:0)
[20:57:19.692] Timer tick, asking Buildbucket for the build status
[20:57:19.756] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:19.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:32:0) after 1m0s
[20:58:19.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:32:0)
[20:58:19.779] Timer tick, asking Buildbucket for the build status
[20:58:19.869] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:19.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:33:0) after 1m0s
[20:59:19.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:33:0)
[20:59:19.885] Timer tick, asking Buildbucket for the build status
[20:59:20.138] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:20.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:34:0) after 1m0s
[21:00:20.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:34:0)
[21:00:20.132] Timer tick, asking Buildbucket for the build status
[21:00:20.253] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:20.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:35:0) after 1m0s
[21:01:20.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:35:0)
[21:01:20.313] Timer tick, asking Buildbucket for the build status
[21:01:20.391] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:36:0) after 1m0s
[21:02:20.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:36:0)
[21:02:20.495] Timer tick, asking Buildbucket for the build status
[21:02:20.558] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:20.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:37:0) after 1m0s
[21:03:20.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:37:0)
[21:03:20.687] Timer tick, asking Buildbucket for the build status
[21:03:21.082] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:21.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:38:0) after 1m0s
[21:04:21.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:38:0)
[21:04:21.132] Timer tick, asking Buildbucket for the build status
[21:04:21.236] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:21.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:39:0) after 1m0s
[21:05:21.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:39:0)
[21:05:21.302] Timer tick, asking Buildbucket for the build status
[21:05:21.485] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:21.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:40:0) after 1m0s
[21:06:21.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:40:0)
[21:06:21.510] Timer tick, asking Buildbucket for the build status
[21:06:21.573] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:21.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:41:0) after 1m0s
[21:07:21.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:41:0)
[21:07:21.597] Timer tick, asking Buildbucket for the build status
[21:07:21.674] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:21.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:42:0) after 1m0s
[21:08:21.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:42:0)
[21:08:21.732] Timer tick, asking Buildbucket for the build status
[21:08:21.833] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:21.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:43:0) after 1m0s
[21:09:21.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:43:0)
[21:09:21.889] Timer tick, asking Buildbucket for the build status
[21:09:21.961] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:21.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:44:0) after 1m0s
[21:10:21.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:44:0)
[21:10:21.973] Timer tick, asking Buildbucket for the build status
[21:10:22.288] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:22.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:45:0) after 1m0s
[21:11:22.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:45:0)
[21:11:22.416] Timer tick, asking Buildbucket for the build status
[21:11:22.593] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:22.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:46:0) after 1m0s
[21:12:22.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:46:0)
[21:12:22.632] Timer tick, asking Buildbucket for the build status
[21:12:22.781] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:22.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:47:0) after 1m0s
[21:13:22.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:47:0)
[21:13:22.809] Timer tick, asking Buildbucket for the build status
[21:13:22.918] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:22.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:48:0) after 1m0s
[21:14:22.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:48:0)
[21:14:22.946] Timer tick, asking Buildbucket for the build status
[21:14:23.166] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:49:0) after 1m0s
[21:15:23.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:49:0)
[21:15:23.237] Timer tick, asking Buildbucket for the build status
[21:15:23.402] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:23.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:50:0) after 1m0s
[21:16:23.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:50:0)
[21:16:23.427] Timer tick, asking Buildbucket for the build status
[21:16:23.506] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:23.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:51:0) after 1m0s
[21:17:23.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:51:0)
[21:17:23.526] Timer tick, asking Buildbucket for the build status
[21:17:23.744] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:23.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:52:0) after 1m0s
[21:18:23.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:52:0)
[21:18:23.807] Timer tick, asking Buildbucket for the build status
[21:18:24.063] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:24.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:53:0) after 1m0s
[21:19:24.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:53:0)
[21:19:24.144] Timer tick, asking Buildbucket for the build status
[21:19:24.469] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:24.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:54:0) after 1m0s
[21:20:24.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:54:0)
[21:20:24.449] Timer tick, asking Buildbucket for the build status
[21:20:24.710] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:24.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:55:0) after 1m0s
[21:21:24.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:55:0)
[21:21:24.763] Timer tick, asking Buildbucket for the build status
[21:21:24.824] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:24.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:56:0) after 1m0s
[21:22:24.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:56:0)
[21:22:24.913] Timer tick, asking Buildbucket for the build status
[21:22:25.001] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:57:0) after 1m0s
[21:23:25.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:57:0)
[21:23:25.608] Timer tick, asking Buildbucket for the build status
[21:23:25.755] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:25.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:58:0) after 1m0s
[21:24:27.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:58:0)
[21:24:27.376] Timer tick, asking Buildbucket for the build status
[21:24:27.443] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:27.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:59:0) after 1m0s
[21:25:27.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:59:0)
[21:25:27.610] Timer tick, asking Buildbucket for the build status
[21:25:27.742] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:27.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:60:0) after 1m0s
[21:26:27.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:60:0)
[21:26:27.731] Timer tick, asking Buildbucket for the build status
[21:26:27.863] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:27.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:61:0) after 1m0s
[21:27:27.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:61:0)
[21:27:27.911] Timer tick, asking Buildbucket for the build status
[21:27:27.979] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:27.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:62:0) after 1m0s
[21:28:28.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:62:0)
[21:28:28.087] Timer tick, asking Buildbucket for the build status
[21:28:28.913] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:28.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:63:0) after 1m0s
[21:29:29.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:63:0)
[21:29:29.083] Timer tick, asking Buildbucket for the build status
[21:29:29.144] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:29.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:64:0) after 1m0s
[21:30:29.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:64:0)
[21:30:29.191] Timer tick, asking Buildbucket for the build status
[21:30:29.253] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:29.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:65:0) after 1m0s
[21:31:29.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:65:0)
[21:31:29.323] Timer tick, asking Buildbucket for the build status
[21:31:29.397] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:66:0) after 1m0s
[21:32:29.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:66:0)
[21:32:29.437] Timer tick, asking Buildbucket for the build status
[21:32:29.518] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:29.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:67:0) after 1m0s
[21:33:29.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:67:0)
[21:33:29.546] Timer tick, asking Buildbucket for the build status
[21:33:29.758] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:68:0) after 1m0s
[21:34:29.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:68:0)
[21:34:29.824] Timer tick, asking Buildbucket for the build status
[21:34:29.893] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:29.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:69:0) after 1m0s
[21:35:29.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:69:0)
[21:35:29.875] Timer tick, asking Buildbucket for the build status
[21:35:29.946] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:29.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:70:0) after 1m0s
[21:36:29.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:70:0)
[21:36:29.966] Timer tick, asking Buildbucket for the build status
[21:36:30.375] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:30.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:71:0) after 1m0s
[21:37:30.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:71:0)
[21:37:30.402] Timer tick, asking Buildbucket for the build status
[21:37:30.456] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:72:0) after 1m0s
[21:38:30.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:72:0)
[21:38:30.518] Timer tick, asking Buildbucket for the build status
[21:38:30.615] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:30.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:73:0) after 1m0s
[21:39:30.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:73:0)
[21:39:30.646] Timer tick, asking Buildbucket for the build status
[21:39:30.891] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:30.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:74:0) after 1m0s
[21:40:30.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:74:0)
[21:40:30.971] Timer tick, asking Buildbucket for the build status
[21:40:31.050] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:31.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:75:0) after 1m0s
[21:41:31.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:75:0)
[21:41:31.075] Timer tick, asking Buildbucket for the build status
[21:41:31.141] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:31.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:76:0) after 1m0s
[21:42:31.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:76:0)
[21:42:31.207] Timer tick, asking Buildbucket for the build status
[21:42:31.409] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:31.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:77:0) after 1m0s
[21:43:31.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:77:0)
[21:43:31.473] Timer tick, asking Buildbucket for the build status
[21:43:31.643] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:31.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:78:0) after 1m0s
[21:44:31.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:78:0)
[21:44:31.705] Timer tick, asking Buildbucket for the build status
[21:44:31.783] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:31.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:79:0) after 1m0s
[21:45:31.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:79:0)
[21:45:31.799] Timer tick, asking Buildbucket for the build status
[21:45:31.858] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:31.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:80:0) after 1m0s
[21:46:31.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:80:0)
[21:46:31.919] Timer tick, asking Buildbucket for the build status
[21:46:32.012] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:32.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:81:0) after 1m0s
[21:47:32.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:81:0)
[21:47:32.050] Timer tick, asking Buildbucket for the build status
[21:47:32.116] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:32.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:82:0) after 1m0s
[21:48:32.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:82:0)
[21:48:32.180] Timer tick, asking Buildbucket for the build status
[21:48:32.294] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:32.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:83:0) after 1m0s
[21:49:32.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:83:0)
[21:49:32.417] Timer tick, asking Buildbucket for the build status
[21:49:32.652] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:32.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:84:0) after 1m0s
[21:50:32.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:84:0)
[21:50:32.833] Timer tick, asking Buildbucket for the build status
[21:50:32.928] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:32.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:85:0) after 1m0s
[21:51:33.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:85:0)
[21:51:33.058] Timer tick, asking Buildbucket for the build status
[21:51:33.116] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:33.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:86:0) after 1m0s
[21:52:33.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:86:0)
[21:52:33.227] Timer tick, asking Buildbucket for the build status
[21:52:33.286] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:33.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:87:0) after 1m0s
[21:53:33.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:87:0)
[21:53:33.418] Timer tick, asking Buildbucket for the build status
[21:53:33.574] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:33.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:88:0) after 1m0s
[21:54:33.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:88:0)
[21:54:33.632] Timer tick, asking Buildbucket for the build status
[21:54:33.710] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:89:0) after 1m0s
[21:55:33.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:89:0)
[21:55:33.735] Timer tick, asking Buildbucket for the build status
[21:55:33.835] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:33.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:90:0) after 1m0s
[21:56:33.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:90:0)
[21:56:33.846] Timer tick, asking Buildbucket for the build status
[21:56:34.063] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:34.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:91:0) after 1m0s
[21:57:34.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:91:0)
[21:57:34.065] Timer tick, asking Buildbucket for the build status
[21:57:34.162] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:34.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:92:0) after 1m0s
[21:58:34.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:92:0)
[21:58:34.169] Timer tick, asking Buildbucket for the build status
[21:58:34.313] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:34.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:93:0) after 1m0s
[21:59:34.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:93:0)
[21:59:34.305] Timer tick, asking Buildbucket for the build status
[21:59:34.364] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:34.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:94:0) after 1m0s
[22:00:34.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:94:0)
[22:00:34.395] Timer tick, asking Buildbucket for the build status
[22:00:35.199] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:35.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:95:0) after 1m0s
[22:01:35.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:95:0)
[22:01:35.421] Timer tick, asking Buildbucket for the build status
[22:01:35.537] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:35.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:96:0) after 1m0s
[22:02:35.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:96:0)
[22:02:35.639] Timer tick, asking Buildbucket for the build status
[22:02:35.704] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:35.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:97:0) after 1m0s
[22:03:35.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:97:0)
[22:03:35.793] Timer tick, asking Buildbucket for the build status
[22:03:36.001] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:36.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:98:0) after 1m0s
[22:04:36.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:98:0)
[22:04:36.102] Timer tick, asking Buildbucket for the build status
[22:04:36.172] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:36.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:99:0) after 1m0s
[22:05:36.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:99:0)
[22:05:36.250] Timer tick, asking Buildbucket for the build status
[22:05:36.366] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:36.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:100:0) after 1m0s
[22:06:36.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:100:0)
[22:06:36.436] Timer tick, asking Buildbucket for the build status
[22:06:36.790] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:36.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:101:0) after 1m0s
[22:07:36.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:101:0)
[22:07:36.899] Timer tick, asking Buildbucket for the build status
[22:07:37.049] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:37.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:102:0) after 1m0s
[22:08:37.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:102:0)
[22:08:37.230] Timer tick, asking Buildbucket for the build status
[22:08:37.414] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:37.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:103:0) after 1m0s
[22:09:37.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:103:0)
[22:09:37.622] Timer tick, asking Buildbucket for the build status
[22:09:37.843] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:37.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:104:0) after 1m0s
[22:10:38.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:104:0)
[22:10:38.023] Timer tick, asking Buildbucket for the build status
[22:10:38.150] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:38.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:105:0) after 1m0s
[22:11:38.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:105:0)
[22:11:38.208] Timer tick, asking Buildbucket for the build status
[22:11:38.433] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:38.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:106:0) after 1m0s
[22:12:38.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:106:0)
[22:12:38.464] Timer tick, asking Buildbucket for the build status
[22:12:39.504] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:39.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:107:0) after 1m0s
[22:13:39.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:107:0)
[22:13:39.592] Timer tick, asking Buildbucket for the build status
[22:13:39.835] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:39.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:108:0) after 1m0s
[22:14:39.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:108:0)
[22:14:39.822] Timer tick, asking Buildbucket for the build status
[22:14:39.940] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:109:0) after 1m0s
[22:15:40.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:109:0)
[22:15:40.064] Timer tick, asking Buildbucket for the build status
[22:15:40.354] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:40.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:110:0) after 1m0s
[22:16:40.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:110:0)
[22:16:40.432] Timer tick, asking Buildbucket for the build status
[22:16:40.540] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:40.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:111:0) after 1m0s
[22:17:40.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:111:0)
[22:17:40.607] Timer tick, asking Buildbucket for the build status
[22:17:40.845] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:40.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:112:0) after 1m0s
[22:18:40.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:112:0)
[22:18:40.933] Timer tick, asking Buildbucket for the build status
[22:18:41.120] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:41.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:113:0) after 1m0s
[22:19:41.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:113:0)
[22:19:41.194] Timer tick, asking Buildbucket for the build status
[22:19:41.543] Build 8864258992638345968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:41.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029689054792278352:114:0) after 1m0s
[22:19:55.490] Received PubSub notification, asking Buildbucket for the build status
[22:19:55.584] Build 8864258992638345968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:19:55.584] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604787593299174",
"created_by": "project:chromium",
"created_ts": "1604780886955469",
"id": "8864258992638345968",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b7c3260313fed9f249de0c54918cd0dd0a107af\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"9b7c3260313fed9f249de0c54918cd0dd0a107af\", \"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-20-nq8x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42675, \"got_angle_revision\": \"18fcb4ad32329764367e08229c4d3e500555b440\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9b7c3260313fed9f249de0c54918cd0dd0a107af\", \"got_revision_cp\": \"refs/heads/master@{#825201}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"4075f123295bb39d50e472b7af1fc04d4f4fe228\", \"got_v8_revision_cp\": \"refs/heads/8.8.250@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b7c3260313fed9f249de0c54918cd0dd0a107af\", \"swarm_hashes_refs/heads/master(at){#825201}_without_patch\": {\"absl_hardening_tests\": \"2074605e114de0a4823920a419127328a0c26ca6\", \"accessibility_unittests\": \"38b2fad068a48374b802ef28984f5e64c828db4b\", \"angle_unittests\": \"079c8f3634921daa8fe9d81483ff036a04e6fa1f\", \"app_list_unittests\": \"9bf3108f530a68da5c92094ecfd897a0fb7d1726\", \"app_shell_unittests\": \"86798b200c0db1dbdff38eaef1494252ff8e8b10\", \"ash_unittests\": \"14507319e304058a7efd9c42167961837453cc18\", \"aura_unittests\": \"a3ab8b48f9cce3a7d88e82e61fff2025504be3a5\", \"base_unittests\": \"10639a6487e06ca2c1f6547d7c783f57f490cf69\", \"base_util_unittests\": \"73d65303556e9b6c01558b4e971741f8d140ae7c\", \"blink_common_unittests\": \"c9ae6abb76d3f861def59072daf3a685aff3b9d7\", \"blink_fuzzer_unittests\": \"0de89f9c74c058928db97a0cd993c0789ace6cda\", \"blink_heap_unittests\": \"e2a3a90dd6c321e9b2491d6aa6f0c652292bf9b2\", \"blink_platform_unittests\": \"a33bc8f014c641777878686bf1d6cb38192bd594\", \"blink_unittests\": \"f300424d7dabbffd5d290e6706063c1e53a0cf71\", \"boringssl_crypto_tests\": \"da82d9af28503304860b8a500a53365811dd260d\", \"boringssl_ssl_tests\": \"32f562e541f517b30e5b0cfe0333f863f54f0081\", \"browser_tests\": \"e17cd51abc41857270da4278fdd5ed488a6c823c\", \"capture_unittests\": \"eb25838c850ec64f9079189bc8854248dbeaa055\", \"cast_unittests\": \"6c1f82607a504cb22190b4f1a42acf1eddb3f656\", \"cc_unittests\": \"7be57cd2719a50310ad17a4357b95a3967b2eb26\", \"chrome_app_unittests\": \"090a9a5b5409cd561107a08475ddbe0ec7d4f2ab\", \"chromedriver_unittests\": \"f0ea049921765ea5f6f1be29f5513ec014aaab60\", \"chromeos_components_unittests\": \"9741aa0f68475d24f78fd35191b51943ca6b6f85\", \"chromeos_unittests\": \"cfea161954ebbac69a52d5bad01e9bf15ae37956\", \"components_browsertests\": \"a1680735b2e08f3cf4e362bc46f30e1beecfdd48\", \"components_unittests\": \"a1c19ea2675142fad46dcce9c18e2cb003945de4\", \"compositor_unittests\": \"90348c746842796e31ab76c01fb13949c390ae1a\", \"content_browsertests\": \"78c494ee797878605f143731580aed68fc187792\", \"content_unittests\": \"cb929cb9e30b56dd2c53c04310f641c4ba9e0e13\", \"crypto_unittests\": \"dfb948bc3e97559be157dd81292bd196ff6d4230\", \"dbus_unittests\": \"ac197c44adc942f054381fefde533ac8a5d7d58e\", \"device_unittests\": \"46772d84339bb020a635f4e35d27865916757b00\", \"display_unittests\": \"e2f7f7dc2a0f6106df69aacd6beb046c1594831f\", \"events_unittests\": \"4ff8e2602f5955c48b728d77a0a0656f27b84e9f\", \"exo_unittests\": \"d2496ac8a692ef8490dcc6675922cf52c2ec9019\", \"extensions_browsertests\": \"2545cc73425ea31e3767db7aafadb63c96afb546\", \"extensions_unittests\": \"7647732119da8b3d503335e7361a8710b0047248\", \"filesystem_service_unittests\": \"b6c669dbc3be3065c6b830175a4d03f69bfb8473\", \"gcm_unit_tests\": \"a02751dfa499e13166ff0b04b4717d23bd5d912e\", \"gfx_unittests\": \"9806a3150c7bd5e9eb981fd277f7f3ad9cceb510\", \"gin_unittests\": \"e45e5cc1d5b8e72f5f295ae543f4215dfacc83a6\", \"gl_unittests_ozone\": \"e8923ed425aaed3d911a4c30bb88d4655d6887d4\", \"google_apis_unittests\": \"d7b4e8dd6b86ad2d7f911db4686687904ea612b1\", \"gpu_unittests\": \"6ee5900c69a28efc567502ab8f02c9b10cda5210\", \"gwp_asan_unittests\": \"bf9a8956edff3016cdcf2525726d7f71302922e1\", \"interactive_ui_tests\": \"b445582c66afe1b60aed4cecad9a242e4f5e673d\", \"ipc_tests\": \"d9fb0ffee6f023982b771abd2b43a03136c1a865\", \"jingle_unittests\": \"00ec9d092ee3994763fa123e2f8dacba99f838da\", \"keyboard_unittests\": \"d33522188da8ef3ec45fc8fc17bb23c8c682fb50\", \"latency_unittests\": \"28028e096b50f326126d726672d1e9c1e07225c7\", \"libjingle_xmpp_unittests\": \"4a68ee11e9b5ea0a6797bb70fd6c5bfb617d4472\", \"liburlpattern_unittests\": \"fbff824392411fd48b408490d1d505e39a13480d\", \"media_blink_unittests\": \"fb170066dd44c6831abbc1871bbd53b00ca019fb\", \"media_unittests\": \"b2c9263d48eff5ccdad42d3fad646146c185cded\", \"message_center_unittests\": \"e81efaf0077b2544d6b7fe36e5e4277d3979a31e\", \"midi_unittests\": \"65d6673a1cf1234a1382d0c4056f371cc024670f\", \"mojo_core_unittests\": \"2ab41669a0028776f60e80b8f8b2c776e5a9162d\", \"mojo_unittests\": \"39a8c7c9da26db08df90535289b107f434da8cdc\", \"nacl_helper_nonsfi_unittests\": \"8b1e43df009732433958d8176ddde8cb5984d68c\", \"nacl_loader_unittests\": \"7cc25647551524838ba849b3e01e91fb7cd62126\", \"native_theme_unittests\": \"659a1660d6d4ae43b9d8aa5eb51d9e03e66cdd70\", \"net_unittests\": \"db8fa5b3eb69d33908099715a3f29342316c6839\", \"ozone_gl_unittests\": \"5d443d2842a8b60bb0747f48622fe67f35c2d380\", \"ozone_unittests\": \"1b21a2b82abb4601cd733dd0c7b1d545e3e35958\", \"ozone_x11_unittests\": \"de7bf1acd25d3f30f877b28ac96ffc42f4d410e9\", \"pdf_unittests\": \"0fefe033a49a66fc29ef6863f7d0bff4a697ac07\", \"perfetto_unittests\": \"875cb367b40786af2622cd5bc4d37095ce1ead67\", \"ppapi_unittests\": \"bdee0b80b3eb80a2fd4f443897d97518abb34544\", \"printing_unittests\": \"e45b86290e79b4a3e04f59fccbfa44b0bda72d07\", \"remoting_unittests\": \"605ef16bf17715a2ee4a87adee208a26a937998e\", \"sandbox_linux_unittests\": \"3e3576ee2fca52e1fe6482f55fe2ddfceeadf338\", \"service_manager_unittests\": \"af07ba32339ffc62710f63a8c143aa81ddcaa85b\", \"services_unittests\": \"e0d91a11e8d77ed57cb5364b72066caef81d9bae\", \"shell_dialogs_unittests\": \"c6f35237e3471397daa069e4615cd644b0f5f0f1\", \"skia_unittests\": \"c0abad7f8d24260a90147684af2664acaff2e5e2\", \"snapshot_unittests\": \"f641f0ded6946942f070e19cfc975cd624998116\", \"sql_unittests\": \"7e51d3a66377aeb982c43a5083d7ab445897378a\", \"storage_unittests\": \"c2e7482e5867afcca76affccacd0fbd9612a19f4\", \"sync_integration_tests\": \"66ad9bbe5604674bb6cddac5dd3bb98e3084ef55\", \"traffic_annotation_auditor_unittests\": \"7c2dbbba643d815246740221b0b305fdbc8654d3\", \"ui_base_unittests\": \"fe295852fd6d0a2a35b1858e56b7e3a2c689214d\", \"ui_chromeos_unittests\": \"2352b466ddf801171895732b0b0cef41ad5dfd80\", \"ui_touch_selection_unittests\": \"f0bc63290146de730b8b0e741848ffbceab17c08\", \"unit_tests\": \"9e92b6b926c91cd3db53cfac7e6c5cc2f9ece0a9\", \"url_unittests\": \"53becd604f45d2a831d2200d10890613e17a28c4\", \"usage_time_limit_unittests\": \"ccbef15ec6b7956958977e18a98804207b7d13f3\", \"views_unittests\": \"3a97b489d3d9837d82ce3e96592930ea3de57b01\", \"viz_unittests\": \"1af4a26640d561e51904c801decd63b81f58166c\", \"wayland_client_perftests\": \"dc1bb50b2d48270044259bc72b4757bc76152850\", \"wm_unittests\": \"8aabd190733a9efef98a5e9d1632c511fa7b9953\", \"wtf_unittests\": \"9fbce3acc5df48ba134c6841e232f9db865bb773\", \"zlib_unittests\": \"15c10e5cf2428ad934a3659e9d12a002c36e50aa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1d6807bf68beb1f6e53b053acbc5bd9e728357249a14ae6e1a69ace8071ddc84_v2\", \"builder_21a338e382a0dbd787bea981107dd7bac336a437920c3cc1b87ea8999b8bcb15_v2\", \"builder_2bbcd421f4eb1113f3a23522b280d70ced47ef846cc2e40eada60721dff8d17e_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_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-20-nq8x\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604782614681027",
"status": "COMPLETED",
"status_changed_ts": "1604787593718836",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42675",
"builder:linux-chromeos-rel",
"buildset:commit/git/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b7c3260313fed9f249de0c54918cd0dd0a107af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029689054792278352",
"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/8864258992638345968/+/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:4fbd36983e9b9a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604787593723680",
"url": "https://ci.chromium.org/b/8864258992638345968",
"utcnow_ts": "1604787595575040"
}
}
[22:19:55.584] Invocation finished in 1h51m50.06679423s with status SUCCEEDED