[08:53:07.179] New invocation is queued and will start shortly
[08:53:08.312] Starting the invocation (attempt 1)
[08:53:08.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:53:08.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:53:08.354] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021035469909252208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021035469909252208",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"gitiles_ref:refs/heads/master"
]
}
[08:53:10.104] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613033588535023",
"id": "8855605407827035456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613033589688819",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22580",
"builder:linux-chromeos-dbg",
"buildset:commit/git/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021035469909252208",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855605407827035456/+/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": "1613033589689076",
"url": "https://ci.chromium.org/b/8855605407827035456",
"utcnow_ts": "1613033590046317"
}
}
[08:53:10.104] Task URL: https://ci.chromium.org/b/8855605407827035456
[08:53:10.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:2:0) after 1m0s
[08:54:10.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:2:0)
[08:54:10.213] Timer tick, asking Buildbucket for the build status
[08:54:10.352] Build 8855605407827035456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:10.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:3:0) after 1m0s
[08:55:05.746] Received PubSub notification, asking Buildbucket for the build status
[08:55:05.809] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:10.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:3:0)
[08:55:10.370] Timer tick, asking Buildbucket for the build status
[08:55:10.661] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:10.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:5:0) after 1m0s
[08:56:10.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:5:0)
[08:56:10.789] Timer tick, asking Buildbucket for the build status
[08:56:10.869] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:10.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:6:0) after 1m0s
[08:57:10.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:6:0)
[08:57:10.900] Timer tick, asking Buildbucket for the build status
[08:57:10.977] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:10.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:7:0) after 1m0s
[08:58:11.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:7:0)
[08:58:11.115] Timer tick, asking Buildbucket for the build status
[08:58:11.188] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:11.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:8:0) after 1m0s
[08:59:11.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:8:0)
[08:59:11.349] Timer tick, asking Buildbucket for the build status
[08:59:11.555] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:11.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:9:0) after 1m0s
[09:00:11.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:9:0)
[09:00:11.594] Timer tick, asking Buildbucket for the build status
[09:00:11.700] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:11.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:10:0) after 1m0s
[09:01:11.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:10:0)
[09:01:11.804] Timer tick, asking Buildbucket for the build status
[09:01:11.891] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:11:0) after 1m0s
[09:02:12.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:11:0)
[09:02:12.029] Timer tick, asking Buildbucket for the build status
[09:02:12.157] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:12.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:12:0) after 1m0s
[09:03:12.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:12:0)
[09:03:12.223] Timer tick, asking Buildbucket for the build status
[09:03:12.692] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:12.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:13:0) after 1m0s
[09:04:12.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:13:0)
[09:04:12.814] Timer tick, asking Buildbucket for the build status
[09:04:12.913] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:12.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:14:0) after 1m0s
[09:05:13.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:14:0)
[09:05:13.023] Timer tick, asking Buildbucket for the build status
[09:05:13.191] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:13.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:15:0) after 1m0s
[09:06:13.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:15:0)
[09:06:13.251] Timer tick, asking Buildbucket for the build status
[09:06:13.358] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:13.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:16:0) after 1m0s
[09:07:13.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:16:0)
[09:07:13.377] Timer tick, asking Buildbucket for the build status
[09:07:13.448] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:13.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:17:0) after 1m0s
[09:08:13.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:17:0)
[09:08:13.486] Timer tick, asking Buildbucket for the build status
[09:08:13.807] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:13.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:18:0) after 1m0s
[09:09:13.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:18:0)
[09:09:13.823] Timer tick, asking Buildbucket for the build status
[09:09:13.987] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:13.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:19:0) after 1m0s
[09:10:14.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:19:0)
[09:10:14.010] Timer tick, asking Buildbucket for the build status
[09:10:14.440] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:14.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:20:0) after 1m0s
[09:11:14.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:20:0)
[09:11:14.485] Timer tick, asking Buildbucket for the build status
[09:11:14.633] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:14.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:21:0) after 1m0s
[09:12:14.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:21:0)
[09:12:14.653] Timer tick, asking Buildbucket for the build status
[09:12:14.768] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:14.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:22:0) after 1m0s
[09:13:14.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:22:0)
[09:13:14.804] Timer tick, asking Buildbucket for the build status
[09:13:14.916] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:14.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:23:0) after 1m0s
[09:14:14.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:23:0)
[09:14:14.952] Timer tick, asking Buildbucket for the build status
[09:14:15.103] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:15.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:24:0) after 1m0s
[09:15:15.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:24:0)
[09:15:15.724] Timer tick, asking Buildbucket for the build status
[09:15:15.844] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:15.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:25:0) after 1m0s
[09:16:15.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:25:0)
[09:16:15.959] Timer tick, asking Buildbucket for the build status
[09:16:16.050] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:16.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:26:0) after 1m0s
[09:17:16.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:26:0)
[09:17:16.074] Timer tick, asking Buildbucket for the build status
[09:17:16.159] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:16.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:27:0) after 1m0s
[09:18:16.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:27:0)
[09:18:16.229] Timer tick, asking Buildbucket for the build status
[09:18:16.318] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:16.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:28:0) after 1m0s
[09:19:16.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:28:0)
[09:19:16.322] Timer tick, asking Buildbucket for the build status
[09:19:16.389] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:16.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:29:0) after 1m0s
[09:20:16.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:29:0)
[09:20:16.406] Timer tick, asking Buildbucket for the build status
[09:20:16.528] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:16.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:30:0) after 1m0s
[09:21:16.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:30:0)
[09:21:16.549] Timer tick, asking Buildbucket for the build status
[09:21:16.848] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:16.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:31:0) after 1m0s
[09:22:16.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:31:0)
[09:22:16.868] Timer tick, asking Buildbucket for the build status
[09:22:17.127] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:17.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:32:0) after 1m0s
[09:23:17.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:32:0)
[09:23:17.233] Timer tick, asking Buildbucket for the build status
[09:23:17.633] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:17.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:33:0) after 1m0s
[09:24:17.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:33:0)
[09:24:17.780] Timer tick, asking Buildbucket for the build status
[09:24:17.961] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:17.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:34:0) after 1m0s
[09:25:18.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:34:0)
[09:25:18.066] Timer tick, asking Buildbucket for the build status
[09:25:18.190] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:18.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:35:0) after 1m0s
[09:26:18.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:35:0)
[09:26:18.325] Timer tick, asking Buildbucket for the build status
[09:26:18.411] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:18.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:36:0) after 1m0s
[09:27:18.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:36:0)
[09:27:18.494] Timer tick, asking Buildbucket for the build status
[09:27:18.846] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:18.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:37:0) after 1m0s
[09:28:18.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:37:0)
[09:28:18.964] Timer tick, asking Buildbucket for the build status
[09:28:19.197] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:19.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:38:0) after 1m0s
[09:29:19.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:38:0)
[09:29:19.210] Timer tick, asking Buildbucket for the build status
[09:29:19.275] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:19.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:39:0) after 1m0s
[09:30:19.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:39:0)
[09:30:19.461] Timer tick, asking Buildbucket for the build status
[09:30:19.765] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:19.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:40:0) after 1m0s
[09:31:19.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:40:0)
[09:31:19.800] Timer tick, asking Buildbucket for the build status
[09:31:19.902] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:41:0) after 1m0s
[09:32:19.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:41:0)
[09:32:19.938] Timer tick, asking Buildbucket for the build status
[09:32:20.022] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:20.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:42:0) after 1m0s
[09:33:20.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:42:0)
[09:33:20.125] Timer tick, asking Buildbucket for the build status
[09:33:20.201] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:20.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:43:0) after 1m0s
[09:34:20.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:43:0)
[09:34:20.266] Timer tick, asking Buildbucket for the build status
[09:34:20.332] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:20.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:44:0) after 1m0s
[09:35:20.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:44:0)
[09:35:20.544] Timer tick, asking Buildbucket for the build status
[09:35:20.726] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:20.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:45:0) after 1m0s
[09:36:20.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:45:0)
[09:36:20.807] Timer tick, asking Buildbucket for the build status
[09:36:20.889] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:20.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:46:0) after 1m0s
[09:37:20.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:46:0)
[09:37:20.887] Timer tick, asking Buildbucket for the build status
[09:37:20.970] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:20.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:47:0) after 1m0s
[09:38:21.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:47:0)
[09:38:21.054] Timer tick, asking Buildbucket for the build status
[09:38:21.235] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:21.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:48:0) after 1m0s
[09:39:21.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:48:0)
[09:39:21.294] Timer tick, asking Buildbucket for the build status
[09:39:21.631] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:21.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:49:0) after 1m0s
[09:40:21.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:49:0)
[09:40:21.733] Timer tick, asking Buildbucket for the build status
[09:40:22.116] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:50:0) after 1m0s
[09:41:22.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:50:0)
[09:41:22.251] Timer tick, asking Buildbucket for the build status
[09:41:23.281] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:23.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:51:0) after 1m0s
[09:42:23.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:51:0)
[09:42:23.418] Timer tick, asking Buildbucket for the build status
[09:42:23.531] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:23.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:52:0) after 1m0s
[09:43:23.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:52:0)
[09:43:23.684] Timer tick, asking Buildbucket for the build status
[09:43:24.225] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:24.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:53:0) after 1m0s
[09:44:24.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:53:0)
[09:44:24.310] Timer tick, asking Buildbucket for the build status
[09:44:24.549] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:24.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:54:0) after 1m0s
[09:45:24.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:54:0)
[09:45:24.574] Timer tick, asking Buildbucket for the build status
[09:45:24.672] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:24.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:55:0) after 1m0s
[09:46:24.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:55:0)
[09:46:24.836] Timer tick, asking Buildbucket for the build status
[09:46:24.926] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:24.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:56:0) after 1m0s
[09:47:25.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:56:0)
[09:47:25.174] Timer tick, asking Buildbucket for the build status
[09:47:25.262] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:25.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:57:0) after 1m0s
[09:48:25.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:57:0)
[09:48:25.415] Timer tick, asking Buildbucket for the build status
[09:48:25.559] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:58:0) after 1m0s
[09:49:25.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:58:0)
[09:49:25.557] Timer tick, asking Buildbucket for the build status
[09:49:25.643] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:25.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:59:0) after 1m0s
[09:50:25.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:59:0)
[09:50:25.781] Timer tick, asking Buildbucket for the build status
[09:50:25.868] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:25.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:60:0) after 1m0s
[09:51:26.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:60:0)
[09:51:26.010] Timer tick, asking Buildbucket for the build status
[09:51:26.104] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:26.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:61:0) after 1m0s
[09:52:26.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:61:0)
[09:52:26.240] Timer tick, asking Buildbucket for the build status
[09:52:26.344] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:26.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:62:0) after 1m0s
[09:53:26.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:62:0)
[09:53:26.487] Timer tick, asking Buildbucket for the build status
[09:53:26.737] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:26.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:63:0) after 1m0s
[09:54:30.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:63:0)
[09:54:30.353] Timer tick, asking Buildbucket for the build status
[09:54:30.441] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:30.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:64:0) after 1m0s
[09:55:30.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:64:0)
[09:55:30.629] Timer tick, asking Buildbucket for the build status
[09:55:30.723] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:65:0) after 1m0s
[09:56:31.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:65:0)
[09:56:31.544] Timer tick, asking Buildbucket for the build status
[09:56:31.649] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:31.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:66:0) after 1m0s
[09:57:31.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:66:0)
[09:57:31.802] Timer tick, asking Buildbucket for the build status
[09:57:32.222] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:32.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:67:0) after 1m0s
[09:58:32.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:67:0)
[09:58:32.290] Timer tick, asking Buildbucket for the build status
[09:58:32.372] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:32.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:68:0) after 1m0s
[09:59:32.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:68:0)
[09:59:32.546] Timer tick, asking Buildbucket for the build status
[09:59:32.653] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:32.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:69:0) after 1m0s
[10:00:32.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:69:0)
[10:00:32.801] Timer tick, asking Buildbucket for the build status
[10:00:33.167] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:33.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:70:0) after 1m0s
[10:01:33.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:70:0)
[10:01:33.241] Timer tick, asking Buildbucket for the build status
[10:01:33.322] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:33.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:71:0) after 1m0s
[10:02:33.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:71:0)
[10:02:33.367] Timer tick, asking Buildbucket for the build status
[10:02:33.460] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:72:0) after 1m0s
[10:03:33.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:72:0)
[10:03:33.511] Timer tick, asking Buildbucket for the build status
[10:03:33.908] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:33.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:73:0) after 1m0s
[10:04:33.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:73:0)
[10:04:33.981] Timer tick, asking Buildbucket for the build status
[10:04:34.102] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:34.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:74:0) after 1m0s
[10:05:34.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:74:0)
[10:05:34.159] Timer tick, asking Buildbucket for the build status
[10:05:34.695] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:34.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:75:0) after 1m0s
[10:06:34.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:75:0)
[10:06:34.768] Timer tick, asking Buildbucket for the build status
[10:06:34.905] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:34.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:76:0) after 1m0s
[10:07:35.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:76:0)
[10:07:35.013] Timer tick, asking Buildbucket for the build status
[10:07:35.242] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:77:0) after 1m0s
[10:08:35.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:77:0)
[10:08:35.391] Timer tick, asking Buildbucket for the build status
[10:08:35.493] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:35.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:78:0) after 1m0s
[10:09:35.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:78:0)
[10:09:35.592] Timer tick, asking Buildbucket for the build status
[10:09:35.712] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:35.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:79:0) after 1m0s
[10:10:35.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:79:0)
[10:10:35.755] Timer tick, asking Buildbucket for the build status
[10:10:35.859] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:35.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:80:0) after 1m0s
[10:11:35.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:80:0)
[10:11:35.889] Timer tick, asking Buildbucket for the build status
[10:11:36.205] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:36.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:81:0) after 1m0s
[10:12:36.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:81:0)
[10:12:36.230] Timer tick, asking Buildbucket for the build status
[10:12:36.372] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:36.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:82:0) after 1m0s
[10:13:36.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:82:0)
[10:13:36.507] Timer tick, asking Buildbucket for the build status
[10:13:36.638] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:36.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:83:0) after 1m0s
[10:14:36.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:83:0)
[10:14:36.667] Timer tick, asking Buildbucket for the build status
[10:14:36.772] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:36.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:84:0) after 1m0s
[10:15:36.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:84:0)
[10:15:36.909] Timer tick, asking Buildbucket for the build status
[10:15:37.000] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:37.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:85:0) after 1m0s
[10:16:37.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:85:0)
[10:16:37.024] Timer tick, asking Buildbucket for the build status
[10:16:37.414] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:37.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:86:0) after 1m0s
[10:17:37.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:86:0)
[10:17:37.467] Timer tick, asking Buildbucket for the build status
[10:17:37.658] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:37.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:87:0) after 1m0s
[10:18:37.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:87:0)
[10:18:37.781] Timer tick, asking Buildbucket for the build status
[10:18:37.901] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:37.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:88:0) after 1m0s
[10:19:37.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:88:0)
[10:19:37.924] Timer tick, asking Buildbucket for the build status
[10:19:38.015] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:38.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:89:0) after 1m0s
[10:20:38.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:89:0)
[10:20:38.055] Timer tick, asking Buildbucket for the build status
[10:20:38.231] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:38.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:90:0) after 1m0s
[10:21:38.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:90:0)
[10:21:38.291] Timer tick, asking Buildbucket for the build status
[10:21:38.508] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:38.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:91:0) after 1m0s
[10:22:38.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:91:0)
[10:22:38.566] Timer tick, asking Buildbucket for the build status
[10:22:38.772] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:38.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:92:0) after 1m0s
[10:23:38.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:92:0)
[10:23:38.835] Timer tick, asking Buildbucket for the build status
[10:23:38.932] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:38.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:93:0) after 1m0s
[10:24:38.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:93:0)
[10:24:38.951] Timer tick, asking Buildbucket for the build status
[10:24:39.044] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:39.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:94:0) after 1m0s
[10:25:39.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:94:0)
[10:25:39.097] Timer tick, asking Buildbucket for the build status
[10:25:39.188] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:39.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:95:0) after 1m0s
[10:26:39.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:95:0)
[10:26:39.354] Timer tick, asking Buildbucket for the build status
[10:26:39.436] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:39.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:96:0) after 1m0s
[10:27:39.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:96:0)
[10:27:39.491] Timer tick, asking Buildbucket for the build status
[10:27:39.574] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:39.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:97:0) after 1m0s
[10:28:39.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:97:0)
[10:28:39.614] Timer tick, asking Buildbucket for the build status
[10:28:39.702] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:39.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:98:0) after 1m0s
[10:29:39.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:98:0)
[10:29:39.848] Timer tick, asking Buildbucket for the build status
[10:29:39.933] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:39.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:99:0) after 1m0s
[10:30:40.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:99:0)
[10:30:40.049] Timer tick, asking Buildbucket for the build status
[10:30:40.166] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:40.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:100:0) after 1m0s
[10:31:40.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:100:0)
[10:31:40.209] Timer tick, asking Buildbucket for the build status
[10:31:40.491] Build 8855605407827035456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:40.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021035469909252208:101:0) after 1m0s
[10:32:04.790] Received PubSub notification, asking Buildbucket for the build status
[10:32:05.023] Build 8855605407827035456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:32:05.023] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613039522587873",
"created_by": "project:chromium",
"created_ts": "1613033588535023",
"id": "8855605407827035456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\"}}",
"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\": \"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\", \"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-77-cvdy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22580, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"7e80cce1a9065d60522d470d24526394d642e6d5\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\", \"got_revision_cp\": \"refs/heads/master@{#853043}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"ef6896f42d709cf53b8b4fe49cef4dedb2c81e32\", \"got_v8_revision_cp\": \"refs/heads/9.0.163@{#1}\", \"got_webrtc_revision\": \"8bf61a3071b7306860261eadf43c157ce3ffe4fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33223}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0\", \"swarm_hashes_refs/heads/master(at){#853043}_without_patch\": {\"absl_hardening_tests\": \"c7b028e84bb9cc2dd8dce7c96bc1efc9953cf05f\", \"accessibility_unittests\": \"dad0a061c11f214ccadd521120cce1d466858b06\", \"angle_unittests\": \"c843b20cfb3321bf48149f515b9d67e99ef603ef\", \"app_list_unittests\": \"759f8af877612d361ce854e4f5614fe227bc21b0\", \"app_shell_unittests\": \"e2181b1b0f69c995bf66a0442fe224ca0c645c14\", \"ash_unittests\": \"4e6a21d418991a253616786be2c57bd4fbf2a85b\", \"aura_unittests\": \"e4d067de1949b2deb0bf980b42f76933b40e2b29\", \"base_unittests\": \"d3af15731fb6c426c8915f9092f3120a4ff5bf08\", \"base_util_unittests\": \"31dffdd79e53f9ef2169480533fb231549b5e7ac\", \"blink_common_unittests\": \"66f04b7765d29f653a082f320f10d62daecb34db\", \"blink_fuzzer_unittests\": \"c706f2fa5a6cd94dcfd7eae992822c7c4b2feac8\", \"blink_heap_unittests\": \"918b176a044ba918ec05929983f718fb53eba343\", \"blink_platform_unittests\": \"e19806ba85558b80cfcc3004da8cf1636e226e42\", \"blink_unittests\": \"e66c5c59dfeeb97a685265030ae8505789a65f5a\", \"boringssl_crypto_tests\": \"f69e67958b22ceffdf296d8300a6de7cad775ba4\", \"boringssl_ssl_tests\": \"728123ffaa330f0becad8392baf727d384993dc8\", \"browser_tests\": \"3e846961aa0c69a32004603325f4d7e54ed228eb\", \"capture_unittests\": \"deeadf0f252a077d0d22983580ae87c83c93205c\", \"cast_unittests\": \"187c49aba6b3cca7cc980171e787397eb8018bb8\", \"cc_unittests\": \"58152b4f23223e3947fd5aef0a9ca207fa5ecf2f\", \"chrome_app_unittests\": \"811794c417e0edf46c054f35cd03f56a99111600\", \"chromedriver_unittests\": \"415dd4a5b76023425c0fee594837d19fab19674b\", \"chromeos_components_unittests\": \"0bbebd9a780ae2edb94c2e8f09133106e4ecae79\", \"chromeos_unittests\": \"adb1dad0bfa062e75b80de5656157e620b714b0b\", \"components_browsertests\": \"5bd3954f16cdf680070b35eac04535b6334c8590\", \"components_unittests\": \"f5af252fdb870e0c40f89ead2914486852173565\", \"compositor_unittests\": \"c2933198522460f6496a1df3ed7588911044ac9b\", \"content_browsertests\": \"473b0a8ec322ecc6bb5c433337f7a847bb81b1d0\", \"content_unittests\": \"c6b771f824dd55231d04888d30b2f0eb1f3efd66\", \"crashpad_tests\": \"a763cac5d82d1a88cbe8a84ee20027802851cefc\", \"crypto_unittests\": \"b127f404cc11e1dbae64c3a3334ec9788e7ab5e8\", \"dbus_unittests\": \"1c2d9c38220aee519f7a79e8744cd32c98d0bd90\", \"device_unittests\": \"3a1144f57f1e3dac3c72e8c5336c1e7058ad9503\", \"display_unittests\": \"85665b272bf7d035fe3059f808aecabb7421673d\", \"events_unittests\": \"ca3ddf39f2064c3fc08ddc4545b96ff3f209b54a\", \"exo_unittests\": \"5c7bb7062c71a35f9fe5a9de6a964e53cbcbb359\", \"extensions_browsertests\": \"02e9c1d37795322eeca52f2c715d4919bebf7dd0\", \"extensions_unittests\": \"97b28b137ad9e42118f1152e9fd4942fff10ede1\", \"filesystem_service_unittests\": \"02d975242087bbc76ca9fa49dac6b4bf1e283f47\", \"gcm_unit_tests\": \"c7ca636365cf46cb1e957f7c7398f924b1956f74\", \"gfx_unittests\": \"71780fe25b7b755023d1c778923cd35f3a0ea06d\", \"gin_unittests\": \"a32f3efde51d40756a34da68e897e7a7aa03feeb\", \"gl_unittests_ozone\": \"cd46e16c7835b9cafb1bd8ad4a8c5a0def518e15\", \"google_apis_unittests\": \"795028446650c3bd3dd8b2c8a292f65a9820c466\", \"gpu_unittests\": \"305ae1e89bf6ab8a24447041654f26d40ed7669c\", \"gwp_asan_unittests\": \"59029b91af9e995726f786a113bc58f854d4c018\", \"interactive_ui_tests\": \"e83b1895030d20108fa2ff77328b77a464ca45dc\", \"ipc_tests\": \"cc2cc275a2f58b7023892246817013d653401006\", \"jingle_unittests\": \"d8ad75139bd8892a2aa0d6dec58268f740a3a9e1\", \"keyboard_unittests\": \"cf6f44a987c4ff6988fa6bb19eecbc5627ea8d82\", \"latency_unittests\": \"7508fc05efd16f83d89c666a239a4ab4ad5860a0\", \"libjingle_xmpp_unittests\": \"accdf686b687c1b4e7aab3ce326e2949ab99d3e8\", \"liburlpattern_unittests\": \"c2f052df196578af0ce082eceb6bf317fb21723d\", \"media_blink_unittests\": \"51787b1bd3a2c0ad0b134d3c1fffd75f6285c858\", \"media_unittests\": \"a1d01b789e0b782f69e0ce372ff5e6cdadba95b1\", \"message_center_unittests\": \"2d148d1a79a32f7f8897b6f45ad8093f5c1fc9ae\", \"midi_unittests\": \"1e6db6a4b213017eef501415f7f8769058b9b076\", \"mojo_core_unittests\": \"e33e944726bc0fe49e3d4866e05f35c4afc699be\", \"mojo_unittests\": \"f2255ef6df622608edbd6ac37c3b75bc5cfa5406\", \"nacl_helper_nonsfi_unittests\": \"5ab7ded9911c0cbcd31a11b5f3b8902f59e6f4ad\", \"nacl_loader_unittests\": \"8e0c039587fd5d5a4b9f50c028303bac302261cc\", \"native_theme_unittests\": \"e8e7212801d4c881162dd7620d7e92596d943fb8\", \"net_unittests\": \"daccd9e765c76a63a524a993a1fb4f9ce5e520dd\", \"ozone_gl_unittests\": \"0b32e482323e126790955e21e55a4c902e443dfc\", \"ozone_unittests\": \"6215ed9091902a19372f556251269e2dabc85d95\", \"ozone_x11_unittests\": \"e4700df5d77b723e8a93801c8cf4967136de9e58\", \"pdf_unittests\": \"c3ba73261833f358a6c08a28393daeb653186199\", \"perfetto_unittests\": \"12d3aa3f7b776406f1993002da813c8d5d2f4e07\", \"ppapi_unittests\": \"b3b1c62cd6ba194dbcf7f88ce1652f93b8cf8567\", \"printing_unittests\": \"80732aafb3f2f0b0ce373b50b6e14382a7127872\", \"remoting_unittests\": \"48acb5b03ff1f443df8dace9b2b12e7c0dbace0c\", \"sandbox_linux_unittests\": \"258a58054795ca631917761f84f0e391b6b5f203\", \"service_manager_unittests\": \"c49f4fdd210345622d1b0794e6d572342a11770f\", \"services_unittests\": \"bf18a125a27df50e1179427b41a8db0033e4fa59\", \"shell_dialogs_unittests\": \"911a6c8a154bfb97e779e4f473f6c89a052af35e\", \"shell_encryption_unittests\": \"98325ebd468a00d7f5851e1409331cb7b84a2322\", \"skia_unittests\": \"bd6f908ef3a96230d6576059da4cc26f44ee71b8\", \"snapshot_unittests\": \"d4b59ee4df291da18e808437814daa7e2fd27348\", \"sql_unittests\": \"817ed3e7c963538fb47afe9c07dacc706d65cbf3\", \"storage_unittests\": \"93c07721c0cba0353eebdfaf9d7c56e6f335a6bf\", \"sync_integration_tests\": \"1194228a734104da5f6f3622797473f8626656fd\", \"traffic_annotation_auditor_unittests\": \"2e9fb58224ae081bf693e3e1212b406ae4e23336\", \"ui_base_unittests\": \"a5ac6f828c1dde5fd183a0179475a821d3a8767b\", \"ui_chromeos_unittests\": \"e031d336af3c8324945ce6287efc9208d910e9ed\", \"ui_touch_selection_unittests\": \"91280418bd088df88078e82128a7a856f47b9940\", \"unit_tests\": \"a5358f21b1be597985a847e67db502027b46778f\", \"url_unittests\": \"435daa2f3fa099a4db47e42c4818ac11a455bc9b\", \"usage_time_limit_unittests\": \"bf99dbf3ac0b29dc16861bee36d842ba52dfbcba\", \"views_unittests\": \"23c45bd544a07a78758c39af1379b4e75085e9cf\", \"viz_unittests\": \"6b0bd29dc7f41017db4a39c69f36e8ecfd4ad012\", \"wm_unittests\": \"1742ea170f7d677984b9315e1ddf074e15309af0\", \"wtf_unittests\": \"b5bec9922e3e90f1e6c9bccada5ea1dc51569a80\", \"zlib_unittests\": \"588b44f8eb0388934af713df095facf76b2bca5d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_67bed7503890836458cdb06475180566d2d2cc5e3d31ceae5acef503f97becf0_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-77-cvdy\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613033705096594",
"status": "COMPLETED",
"status_changed_ts": "1613039523295436",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22580",
"builder:linux-chromeos-dbg",
"buildset:commit/git/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01d1d630b58ec2880cab1d6a3b2fdc1cb387a2d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021035469909252208",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855605407827035456/+/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:51a91cd5b7829b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613039523326945",
"url": "https://ci.chromium.org/b/8855605407827035456",
"utcnow_ts": "1613039525001701"
}
}
[10:32:05.023] Invocation finished in 1h38m57.85875016s with status SUCCEEDED