[23:40:49.717] New invocation is queued and will start shortly
[23:40:50.910] Starting the invocation (attempt 1)
[23:40:50.944] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:40:50.948] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:40:50.948] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030945286397076736",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"700d7ed6990798f21b80a7a742221630e568f216\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9030945286397076736",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/700d7ed6990798f21b80a7a742221630e568f216",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/700d7ed6990798f21b80a7a742221630e568f216",
"gitiles_ref:refs/heads/master"
]
}
[23:40:51.732] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603582851029647",
"id": "8865515224357405088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"700d7ed6990798f21b80a7a742221630e568f216\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603582851606892",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42235",
"builder:linux-chromeos-rel",
"buildset:commit/git/700d7ed6990798f21b80a7a742221630e568f216",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/700d7ed6990798f21b80a7a742221630e568f216",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030945286397076736",
"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/8865515224357405088/+/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": "1603582851607082",
"url": "https://ci.chromium.org/b/8865515224357405088",
"utcnow_ts": "1603582851723696"
}
}
[23:40:51.732] Task URL: https://ci.chromium.org/b/8865515224357405088
[23:40:51.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:2:0) after 1m0s
[23:41:51.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:2:0)
[23:41:51.779] Timer tick, asking Buildbucket for the build status
[23:41:51.881] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:51.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:3:0) after 1m0s
[23:42:51.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:3:0)
[23:42:51.985] Timer tick, asking Buildbucket for the build status
[23:42:52.576] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:52.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:4:0) after 1m0s
[23:43:52.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:4:0)
[23:43:52.687] Timer tick, asking Buildbucket for the build status
[23:43:52.751] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:52.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:5:0) after 1m0s
[23:44:52.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:5:0)
[23:44:52.771] Timer tick, asking Buildbucket for the build status
[23:44:52.842] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:52.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:6:0) after 1m0s
[23:45:52.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:6:0)
[23:45:52.860] Timer tick, asking Buildbucket for the build status
[23:45:52.995] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:52.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:7:0) after 1m0s
[23:46:52.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:7:0)
[23:46:52.995] Timer tick, asking Buildbucket for the build status
[23:46:53.073] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:53.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:8:0) after 1m0s
[23:47:53.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:8:0)
[23:47:53.143] Timer tick, asking Buildbucket for the build status
[23:47:53.216] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:53.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:9:0) after 1m0s
[23:48:53.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:9:0)
[23:48:53.283] Timer tick, asking Buildbucket for the build status
[23:48:53.370] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:53.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:10:0) after 1m0s
[23:49:53.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:10:0)
[23:49:53.488] Timer tick, asking Buildbucket for the build status
[23:49:53.569] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:11:0) after 1m0s
[23:50:53.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:11:0)
[23:50:53.553] Timer tick, asking Buildbucket for the build status
[23:50:53.680] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:53.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:12:0) after 1m0s
[23:51:53.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:12:0)
[23:51:53.706] Timer tick, asking Buildbucket for the build status
[23:51:53.771] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:53.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:13:0) after 1m0s
[23:52:55.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:13:0)
[23:52:55.543] Timer tick, asking Buildbucket for the build status
[23:52:55.628] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:55.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:14:0) after 1m0s
[23:53:56.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:14:0)
[23:53:56.372] Timer tick, asking Buildbucket for the build status
[23:53:56.450] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:56.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:15:0) after 1m0s
[23:54:56.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:15:0)
[23:54:56.601] Timer tick, asking Buildbucket for the build status
[23:54:56.671] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:56.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:16:0) after 1m0s
[23:55:56.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:16:0)
[23:55:56.792] Timer tick, asking Buildbucket for the build status
[23:55:57.113] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:57.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:17:0) after 1m0s
[23:56:57.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:17:0)
[23:56:57.181] Timer tick, asking Buildbucket for the build status
[23:56:57.255] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:57.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:18:0) after 1m0s
[23:57:57.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:18:0)
[23:57:57.338] Timer tick, asking Buildbucket for the build status
[23:57:57.414] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:57.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:19:0) after 1m0s
[23:58:57.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:19:0)
[23:58:57.514] Timer tick, asking Buildbucket for the build status
[23:58:57.601] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:57.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:20:0) after 1m0s
[23:59:57.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:20:0)
[23:59:57.622] Timer tick, asking Buildbucket for the build status
[23:59:57.718] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:57.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:21:0) after 1m0s
[00:00:57.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:21:0)
[00:00:57.738] Timer tick, asking Buildbucket for the build status
[00:00:58.079] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:58.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:22:0) after 1m0s
[00:01:58.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:22:0)
[00:01:58.316] Timer tick, asking Buildbucket for the build status
[00:01:58.389] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:58.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:23:0) after 1m0s
[00:02:58.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:23:0)
[00:02:58.561] Timer tick, asking Buildbucket for the build status
[00:02:58.646] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:58.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:24:0) after 1m0s
[00:03:59.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:24:0)
[00:03:59.102] Timer tick, asking Buildbucket for the build status
[00:03:59.170] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:59.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:25:0) after 1m0s
[00:04:59.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:25:0)
[00:04:59.378] Timer tick, asking Buildbucket for the build status
[00:04:59.931] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:59.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:26:0) after 1m0s
[00:05:59.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:26:0)
[00:05:59.954] Timer tick, asking Buildbucket for the build status
[00:06:00.291] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:00.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:27:0) after 1m0s
[00:07:01.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:27:0)
[00:07:01.564] Timer tick, asking Buildbucket for the build status
[00:07:01.788] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:01.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:28:0) after 1m0s
[00:08:02.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:28:0)
[00:08:02.368] Timer tick, asking Buildbucket for the build status
[00:08:02.459] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:02.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:29:0) after 1m0s
[00:09:02.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:29:0)
[00:09:02.907] Timer tick, asking Buildbucket for the build status
[00:09:02.995] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:02.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:30:0) after 1m0s
[00:10:03.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:30:0)
[00:10:03.065] Timer tick, asking Buildbucket for the build status
[00:10:03.149] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:03.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:31:0) after 1m0s
[00:11:03.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:31:0)
[00:11:03.242] Timer tick, asking Buildbucket for the build status
[00:11:03.353] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:03.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:32:0) after 1m0s
[00:12:03.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:32:0)
[00:12:03.392] Timer tick, asking Buildbucket for the build status
[00:12:03.475] Build 8865515224357405088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:03.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:33:0) after 1m0s
[00:12:03.601] Received PubSub notification, asking Buildbucket for the build status
[00:12:03.712] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:03.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:33:0)
[00:13:03.562] Timer tick, asking Buildbucket for the build status
[00:13:03.781] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:35:0) after 1m0s
[00:14:03.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:35:0)
[00:14:03.932] Timer tick, asking Buildbucket for the build status
[00:14:04.148] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:04.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:36:0) after 1m0s
[00:15:04.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:36:0)
[00:15:04.297] Timer tick, asking Buildbucket for the build status
[00:15:04.393] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:04.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:37:0) after 1m0s
[00:16:04.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:37:0)
[00:16:04.547] Timer tick, asking Buildbucket for the build status
[00:16:05.017] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:05.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:38:0) after 1m0s
[00:17:05.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:38:0)
[00:17:05.110] Timer tick, asking Buildbucket for the build status
[00:17:05.181] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:05.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:39:0) after 1m0s
[00:18:05.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:39:0)
[00:18:05.471] Timer tick, asking Buildbucket for the build status
[00:18:05.601] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:05.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:40:0) after 1m0s
[00:19:05.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:40:0)
[00:19:05.615] Timer tick, asking Buildbucket for the build status
[00:19:05.866] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:05.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:41:0) after 1m0s
[00:20:05.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:41:0)
[00:20:05.905] Timer tick, asking Buildbucket for the build status
[00:20:06.014] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:06.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:42:0) after 1m0s
[00:21:06.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:42:0)
[00:21:06.214] Timer tick, asking Buildbucket for the build status
[00:21:06.334] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:06.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:43:0) after 1m0s
[00:22:06.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:43:0)
[00:22:06.384] Timer tick, asking Buildbucket for the build status
[00:22:07.016] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:07.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:44:0) after 1m0s
[00:23:07.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:44:0)
[00:23:07.266] Timer tick, asking Buildbucket for the build status
[00:23:07.358] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:07.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:45:0) after 1m0s
[00:24:07.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:45:0)
[00:24:07.589] Timer tick, asking Buildbucket for the build status
[00:24:08.316] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:08.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:46:0) after 1m0s
[00:25:08.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:46:0)
[00:25:08.761] Timer tick, asking Buildbucket for the build status
[00:25:09.201] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:09.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:47:0) after 1m0s
[00:26:09.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:47:0)
[00:26:09.286] Timer tick, asking Buildbucket for the build status
[00:26:09.397] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:09.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:48:0) after 1m0s
[00:27:09.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:48:0)
[00:27:09.482] Timer tick, asking Buildbucket for the build status
[00:27:09.544] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:09.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:49:0) after 1m0s
[00:28:09.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:49:0)
[00:28:09.833] Timer tick, asking Buildbucket for the build status
[00:28:10.122] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:10.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:50:0) after 1m0s
[00:29:10.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:50:0)
[00:29:10.245] Timer tick, asking Buildbucket for the build status
[00:29:10.319] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:10.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:51:0) after 1m0s
[00:30:10.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:51:0)
[00:30:10.416] Timer tick, asking Buildbucket for the build status
[00:30:10.480] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:10.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:52:0) after 1m0s
[00:31:11.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:52:0)
[00:31:11.155] Timer tick, asking Buildbucket for the build status
[00:31:11.258] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:11.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:53:0) after 1m0s
[00:32:11.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:53:0)
[00:32:11.436] Timer tick, asking Buildbucket for the build status
[00:32:11.499] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:11.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:54:0) after 1m0s
[00:33:11.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:54:0)
[00:33:11.575] Timer tick, asking Buildbucket for the build status
[00:33:11.642] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:11.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:55:0) after 1m0s
[00:34:11.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:55:0)
[00:34:11.688] Timer tick, asking Buildbucket for the build status
[00:34:11.759] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:56:0) after 1m0s
[00:35:11.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:56:0)
[00:35:11.971] Timer tick, asking Buildbucket for the build status
[00:35:12.069] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:12.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:57:0) after 1m0s
[00:36:12.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:57:0)
[00:36:12.216] Timer tick, asking Buildbucket for the build status
[00:36:12.321] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:12.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:58:0) after 1m0s
[00:37:12.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:58:0)
[00:37:12.345] Timer tick, asking Buildbucket for the build status
[00:37:12.402] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:12.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:59:0) after 1m0s
[00:38:12.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:59:0)
[00:38:12.599] Timer tick, asking Buildbucket for the build status
[00:38:12.785] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:12.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:60:0) after 1m0s
[00:39:12.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:60:0)
[00:39:12.926] Timer tick, asking Buildbucket for the build status
[00:39:12.987] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:12.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:61:0) after 1m0s
[00:40:13.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:61:0)
[00:40:13.179] Timer tick, asking Buildbucket for the build status
[00:40:13.258] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:13.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:62:0) after 1m0s
[00:41:13.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:62:0)
[00:41:13.355] Timer tick, asking Buildbucket for the build status
[00:41:13.453] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:13.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:63:0) after 1m0s
[00:42:13.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:63:0)
[00:42:13.548] Timer tick, asking Buildbucket for the build status
[00:42:13.701] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:13.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:64:0) after 1m0s
[00:43:13.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:64:0)
[00:43:13.757] Timer tick, asking Buildbucket for the build status
[00:43:13.827] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:13.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:65:0) after 1m0s
[00:44:13.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:65:0)
[00:44:13.875] Timer tick, asking Buildbucket for the build status
[00:44:13.936] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:13.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:66:0) after 1m0s
[00:45:13.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:66:0)
[00:45:13.974] Timer tick, asking Buildbucket for the build status
[00:45:14.046] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:14.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:67:0) after 1m0s
[00:46:14.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:67:0)
[00:46:14.073] Timer tick, asking Buildbucket for the build status
[00:46:14.180] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:14.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:68:0) after 1m0s
[00:47:14.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:68:0)
[00:47:14.199] Timer tick, asking Buildbucket for the build status
[00:47:14.297] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:14.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:69:0) after 1m0s
[00:48:14.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:69:0)
[00:48:14.326] Timer tick, asking Buildbucket for the build status
[00:48:14.382] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:14.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:70:0) after 1m0s
[00:49:14.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:70:0)
[00:49:14.387] Timer tick, asking Buildbucket for the build status
[00:49:14.443] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:14.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:71:0) after 1m0s
[00:50:14.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:71:0)
[00:50:14.480] Timer tick, asking Buildbucket for the build status
[00:50:14.537] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:14.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:72:0) after 1m0s
[00:51:14.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:72:0)
[00:51:14.565] Timer tick, asking Buildbucket for the build status
[00:51:14.674] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:14.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:73:0) after 1m0s
[00:52:14.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:73:0)
[00:52:14.694] Timer tick, asking Buildbucket for the build status
[00:52:14.763] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:14.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:74:0) after 1m0s
[00:53:14.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:74:0)
[00:53:14.844] Timer tick, asking Buildbucket for the build status
[00:53:14.909] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:14.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:75:0) after 1m0s
[00:54:15.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:75:0)
[00:54:15.115] Timer tick, asking Buildbucket for the build status
[00:54:15.173] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:15.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:76:0) after 1m0s
[00:55:15.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:76:0)
[00:55:15.406] Timer tick, asking Buildbucket for the build status
[00:55:15.507] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:15.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:77:0) after 1m0s
[00:56:15.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:77:0)
[00:56:15.681] Timer tick, asking Buildbucket for the build status
[00:56:15.792] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:15.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:78:0) after 1m0s
[00:57:15.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:78:0)
[00:57:15.847] Timer tick, asking Buildbucket for the build status
[00:57:16.007] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:16.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:79:0) after 1m0s
[00:58:16.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:79:0)
[00:58:16.146] Timer tick, asking Buildbucket for the build status
[00:58:16.210] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:16.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:80:0) after 1m0s
[00:59:16.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:80:0)
[00:59:16.618] Timer tick, asking Buildbucket for the build status
[00:59:16.676] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:16.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:81:0) after 1m0s
[01:00:16.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:81:0)
[01:00:16.849] Timer tick, asking Buildbucket for the build status
[01:00:16.946] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:16.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:82:0) after 1m0s
[01:01:17.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:82:0)
[01:01:17.052] Timer tick, asking Buildbucket for the build status
[01:01:17.164] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:17.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:83:0) after 1m0s
[01:02:17.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:83:0)
[01:02:17.353] Timer tick, asking Buildbucket for the build status
[01:02:17.495] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:17.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:84:0) after 1m0s
[01:03:17.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:84:0)
[01:03:17.581] Timer tick, asking Buildbucket for the build status
[01:03:17.645] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:17.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:85:0) after 1m0s
[01:04:17.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:85:0)
[01:04:17.620] Timer tick, asking Buildbucket for the build status
[01:04:17.680] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:17.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:86:0) after 1m0s
[01:05:17.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:86:0)
[01:05:17.728] Timer tick, asking Buildbucket for the build status
[01:05:18.024] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:18.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:87:0) after 1m0s
[01:06:18.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:87:0)
[01:06:18.084] Timer tick, asking Buildbucket for the build status
[01:06:18.197] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:18.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:88:0) after 1m0s
[01:07:18.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:88:0)
[01:07:18.194] Timer tick, asking Buildbucket for the build status
[01:07:18.258] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:18.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:89:0) after 1m0s
[01:08:18.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:89:0)
[01:08:18.274] Timer tick, asking Buildbucket for the build status
[01:08:18.334] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:18.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:90:0) after 1m0s
[01:09:18.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:90:0)
[01:09:18.568] Timer tick, asking Buildbucket for the build status
[01:09:18.630] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:18.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:91:0) after 1m0s
[01:10:18.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:91:0)
[01:10:18.685] Timer tick, asking Buildbucket for the build status
[01:10:18.753] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:18.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:92:0) after 1m0s
[01:11:18.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:92:0)
[01:11:18.895] Timer tick, asking Buildbucket for the build status
[01:11:19.043] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:19.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:93:0) after 1m0s
[01:12:19.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:93:0)
[01:12:19.193] Timer tick, asking Buildbucket for the build status
[01:12:19.315] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:19.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:94:0) after 1m0s
[01:13:19.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:94:0)
[01:13:19.407] Timer tick, asking Buildbucket for the build status
[01:13:19.505] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:19.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:95:0) after 1m0s
[01:14:19.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:95:0)
[01:14:19.641] Timer tick, asking Buildbucket for the build status
[01:14:19.693] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:19.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:96:0) after 1m0s
[01:15:19.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:96:0)
[01:15:19.806] Timer tick, asking Buildbucket for the build status
[01:15:19.856] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:19.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:97:0) after 1m0s
[01:16:19.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:97:0)
[01:16:19.904] Timer tick, asking Buildbucket for the build status
[01:16:20.007] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:20.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:98:0) after 1m0s
[01:17:20.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:98:0)
[01:17:20.122] Timer tick, asking Buildbucket for the build status
[01:17:20.215] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:99:0) after 1m0s
[01:18:20.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:99:0)
[01:18:20.286] Timer tick, asking Buildbucket for the build status
[01:18:20.388] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:20.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:100:0) after 1m0s
[01:19:20.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:100:0)
[01:19:20.531] Timer tick, asking Buildbucket for the build status
[01:19:20.637] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:20.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:101:0) after 1m0s
[01:20:20.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:101:0)
[01:20:20.658] Timer tick, asking Buildbucket for the build status
[01:20:20.977] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:20.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:102:0) after 1m0s
[01:21:20.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:102:0)
[01:21:20.992] Timer tick, asking Buildbucket for the build status
[01:21:21.275] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:21.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:103:0) after 1m0s
[01:22:21.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:103:0)
[01:22:21.300] Timer tick, asking Buildbucket for the build status
[01:22:21.409] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:21.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:104:0) after 1m0s
[01:23:21.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:104:0)
[01:23:21.400] Timer tick, asking Buildbucket for the build status
[01:23:23.217] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:23.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:105:0) after 1m0s
[01:24:23.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:105:0)
[01:24:23.338] Timer tick, asking Buildbucket for the build status
[01:24:23.591] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:23.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:106:0) after 1m0s
[01:25:23.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:106:0)
[01:25:23.679] Timer tick, asking Buildbucket for the build status
[01:25:23.827] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:23.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:107:0) after 1m0s
[01:26:23.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:107:0)
[01:26:23.900] Timer tick, asking Buildbucket for the build status
[01:26:24.026] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:24.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:108:0) after 1m0s
[01:27:24.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:108:0)
[01:27:24.138] Timer tick, asking Buildbucket for the build status
[01:27:24.785] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:24.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:109:0) after 1m0s
[01:28:24.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:109:0)
[01:28:24.871] Timer tick, asking Buildbucket for the build status
[01:28:25.028] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:25.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:110:0) after 1m0s
[01:29:25.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:110:0)
[01:29:25.118] Timer tick, asking Buildbucket for the build status
[01:29:25.599] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:25.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:111:0) after 1m0s
[01:30:25.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:111:0)
[01:30:25.715] Timer tick, asking Buildbucket for the build status
[01:30:25.820] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:25.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:112:0) after 1m0s
[01:31:26.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:112:0)
[01:31:26.028] Timer tick, asking Buildbucket for the build status
[01:31:26.256] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:26.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:113:0) after 1m0s
[01:32:26.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:113:0)
[01:32:26.447] Timer tick, asking Buildbucket for the build status
[01:32:26.583] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:26.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:114:0) after 1m0s
[01:33:26.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:114:0)
[01:33:26.777] Timer tick, asking Buildbucket for the build status
[01:33:26.916] Build 8865515224357405088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:26.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030945286397076736:115:0) after 1m0s
[01:34:25.289] Received PubSub notification, asking Buildbucket for the build status
[01:34:25.353] Build 8865515224357405088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:34:25.353] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603589664219015",
"created_by": "project:chromium",
"created_ts": "1603582851029647",
"id": "8865515224357405088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"700d7ed6990798f21b80a7a742221630e568f216\"}}",
"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\": \"700d7ed6990798f21b80a7a742221630e568f216\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-53-2k1u\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42235, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"700d7ed6990798f21b80a7a742221630e568f216\", \"got_revision_cp\": \"refs/heads/master@{#820590}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"3065e6ef3016a906d1db82e1d4322cbd53d43247\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32481}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"700d7ed6990798f21b80a7a742221630e568f216\", \"swarm_hashes_refs/heads/master(at){#820590}_without_patch\": {\"absl_hardening_tests\": \"688545d994f6532d7f0b85189d8f075c04666cf9\", \"accessibility_unittests\": \"3fd668c03d0b326bcee7017f62235b26b345e964\", \"angle_unittests\": \"edf535c474337c6c4dfe3adea646df621b9c5ee1\", \"app_list_unittests\": \"2e3573bdc5aeba76edf137ba24061708d2715a52\", \"app_shell_unittests\": \"01452518d9ea4878846577479b8afa4e0462c167\", \"ash_unittests\": \"74ba7cf5c478593e8f96ad0409ae8c2075eb2bd4\", \"aura_unittests\": \"2d83bce9bf6cf54a01b23081cd01a8dbe8ef9a17\", \"base_unittests\": \"b09c045939ac0f926d03a4cd13aea2a3e9399128\", \"base_util_unittests\": \"663d99147321ca41b0dc4bdd5e9016d09cd69722\", \"blink_common_unittests\": \"94dc8bc5e884f651a97c174a31855aebf2ca5456\", \"blink_fuzzer_unittests\": \"960cb38bcc71891a97ff551a1fa6d92ce38fc390\", \"blink_heap_unittests\": \"53d6ad7e3d5f4583845ff32fb1c34b46254edc3c\", \"blink_platform_unittests\": \"f672084fc62b3b4218f6d2316ed000d2a2b994e1\", \"blink_unittests\": \"d441e006956b9b0da1a1f845a2c3e849b166f3c1\", \"boringssl_crypto_tests\": \"5623290ab35bb3a9e56d9c02ef041d8399cb2408\", \"boringssl_ssl_tests\": \"890ac51bfa579d02ed27dbcd8425714c0af2ed42\", \"browser_tests\": \"0f510f213e57e1d0bf53a85993cba897fa596899\", \"capture_unittests\": \"b5c365b9d2758b88feef2c3eaa88306daf22f42a\", \"cast_unittests\": \"4d5478da926fcfe829ba9aea65666298d03b1ed4\", \"cc_unittests\": \"5f5e814240e3bec069997f5c3f9bceb43ba26510\", \"chrome_app_unittests\": \"fcc5ff8ae3b6c67c31b1fc1eb3385fc8844b282b\", \"chromedriver_unittests\": \"d70e763e75a0631c24b286d2f9d5603a6b15a80f\", \"chromeos_components_unittests\": \"53ac7939f45317bcc67b7c8ee8ca68c034a456a6\", \"chromeos_unittests\": \"6c0f565fb0a647c77270296ccda93cbbc05e0d90\", \"components_browsertests\": \"ab172f679b40a33fbd35c302c43849dd4fd54df2\", \"components_unittests\": \"373f2d0688278531387e1b6ea0fba47cd8fa1600\", \"compositor_unittests\": \"f31a096f27960e38b0380ed8ff3322cd4726ecb7\", \"content_browsertests\": \"cbdbf91b41d06079bb565f0b3288228ac93491c2\", \"content_unittests\": \"6fc423df8d32622ac777f9142fd641f0e7a6763a\", \"crypto_unittests\": \"1b5dd23cfac7a6653dad131544295ff13eba92c5\", \"dbus_unittests\": \"8eb7d227c09c9fa029fee5cb948fe3cca71708d8\", \"device_unittests\": \"1107ce1319a4e4a0345f91ab7eb492767193ca04\", \"display_unittests\": \"ca5bc9425fb7bc3959ba38e0ec687444c29d55db\", \"events_unittests\": \"b388d6e04ad5c8c35c709e2ea61e48dad37d32c6\", \"exo_unittests\": \"ba534a41cfc3f139a3a4bf931e21f0bfa72d3d54\", \"extensions_browsertests\": \"1d1549c768f1619c99bd7a4c06ad6e88ab88791f\", \"extensions_unittests\": \"d7a5108af60665d0effecf4820934e582b185640\", \"filesystem_service_unittests\": \"110cea0c2c474c12e8804765745c3297684ec362\", \"gcm_unit_tests\": \"e3fdee2867e650f1329e2f610ecaee59e2a4b75f\", \"gfx_unittests\": \"6080128caff57aecf88a02c9648366c794367d20\", \"gin_unittests\": \"6b5f99ec5703e0e7e7dd1e745326042351302b7b\", \"gl_unittests_ozone\": \"84332d896de1887007510613aff9ee7235f62b49\", \"google_apis_unittests\": \"3755fdba12a83b5e35a50ea12362ab3b38dc9498\", \"gpu_unittests\": \"2643abc398c8c4d186bd76b72f6163f097b22575\", \"gwp_asan_unittests\": \"48e4085ea948aef88fec7f62fb96e8d5e9420e29\", \"interactive_ui_tests\": \"ba24be17e5341990b2728ef2e3e2ef920e3db710\", \"ipc_tests\": \"3d79c078491eee4c48ed631d4fd4154808480700\", \"jingle_unittests\": \"450528fc7493d85aa692e4cd7cadcdc3bff3dcff\", \"keyboard_unittests\": \"0d8cbc3b5ef3de25147437cb266237c4d2d94ef5\", \"latency_unittests\": \"13b1e20efd05c0c57537ddf5f28fb4d58acbf6ac\", \"libjingle_xmpp_unittests\": \"1266a0eede82b2671dd66a5417c7e1c8653b8c09\", \"media_blink_unittests\": \"65ed0929817bb81666ce3ec1bdb62ca1c6479106\", \"media_unittests\": \"ea06ac9c8d3afbb8f8be4094cc3f8b600adbfe2a\", \"message_center_unittests\": \"5cc6821146745ff4c7f7b2ed337ead85582c00a3\", \"midi_unittests\": \"dab0b1ccfe5b85065c27d38555fb7e75352acd3d\", \"mojo_core_unittests\": \"3406d38dc4e359a1f573dd8b13b684c659cf2348\", \"mojo_unittests\": \"e6feed74142c41fbea99589845ab175452e34a5a\", \"nacl_helper_nonsfi_unittests\": \"66cb5a98a4c30875ec3e22118c472788989ba1c0\", \"nacl_loader_unittests\": \"2a3450fae82adab054e436bdd17dd5423dbb3143\", \"native_theme_unittests\": \"ef47cddf9401de3326f3af11516fb231a446a3f4\", \"net_unittests\": \"47f716b67dbb48bf6ddd412178b61a8359e7f5dc\", \"ozone_gl_unittests\": \"3997b3e74fea7313a8191dd80949854b6870006f\", \"ozone_unittests\": \"d7a43f8e83f4447e11070020a2ff7cc8916b1aa6\", \"ozone_x11_unittests\": \"f9863df7d6ccae17fef955b99895de3db278c429\", \"pdf_unittests\": \"148a0af0515180ce1d21ac25bf5b6a587ae592cf\", \"perfetto_unittests\": \"650ae8effb6795b98a4f3d8b73dc4e60efa21f83\", \"ppapi_unittests\": \"762deaf974b7cc7702de2c53c3d84ba5603c6709\", \"printing_unittests\": \"6b0ebc7b68847665c2911e0ee5a4ea858ab7e7fd\", \"remoting_unittests\": \"3dce17aa20a4cffbee1426b15ab5724a479f9dad\", \"sandbox_linux_unittests\": \"fea96530565e0495689ed51085234f0f4494f882\", \"service_manager_unittests\": \"f8b680c9320976df811c4bc44626524b93154985\", \"services_unittests\": \"01f2f9238c3e8ae39039d7a71616693b8b3c1f5d\", \"shell_dialogs_unittests\": \"a8b70d8a6548f3836fd1761c7b840c281733f712\", \"skia_unittests\": \"c2ec6a33a7f7c4cad390c2583f32202342cdfba8\", \"snapshot_unittests\": \"1393466d5a372d8390038e9b598d34bac65f4a97\", \"sql_unittests\": \"7ff19f23e7da379c1e49c06030f603fe8169a7d3\", \"storage_unittests\": \"6869f87d0af2605ddab5e53e5ec70f65c0ec3f8b\", \"sync_integration_tests\": \"a93818f0303efe1849c4a194737bb78aa591a22d\", \"traffic_annotation_auditor_unittests\": \"b524696d432752955843976049f7393a8b279415\", \"ui_base_unittests\": \"6321f5d9586c5e05bca9bb25aca2384c0b9ad5b4\", \"ui_chromeos_unittests\": \"0a178c0528d4189f39e57bfa6e485aaf2f09f8f5\", \"ui_touch_selection_unittests\": \"083b952cffac8c1327ed38adf2cf17a2e9016a77\", \"unit_tests\": \"d999f1d559ae6b1461d0a0774ec7001422679687\", \"url_unittests\": \"ac74ef8e2bbb2e907ec44d67b2c5203cb61977a7\", \"usage_time_limit_unittests\": \"bc8678fde5f51ef189cc7dffad12397e2cb4445f\", \"views_unittests\": \"46c208c5bd83218005b120a41926df512e782adb\", \"viz_unittests\": \"31692ea87f35348833e8291ac469e252758ca4c0\", \"wayland_client_perftests\": \"131493fe7c0b926ea0795aaa6a22fa8745199c84\", \"wm_unittests\": \"059ef546c30ed88e0e05945e8358d0f1144b0acd\", \"wtf_unittests\": \"6249fa41e4798532b7772595d13b5f6b223ce02d\", \"zlib_unittests\": \"6ca90be974f4fe5e8a10d9ae601dea0e2dff5ec2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_5031bc3d586fdedb2b02f62dae64b9ebfd5c405012b7a28b41092bb1d66cfccb_v2\", \"builder_77831a9853858ae8dfaadf9044828f8a9da2fc7dbb3a86e9f358018483ddf65f_v2\", \"builder_943b3d953accc151615f961b0fe86001055e92f28d1a90dbbb516f7ec27e4183_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-53-2k1u\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603584722966455",
"status": "COMPLETED",
"status_changed_ts": "1603589664663372",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42235",
"builder:linux-chromeos-rel",
"buildset:commit/git/700d7ed6990798f21b80a7a742221630e568f216",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/700d7ed6990798f21b80a7a742221630e568f216",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030945286397076736",
"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/8865515224357405088/+/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:4f75ce030ce52010",
"user_agent:luci-scheduler"
],
"updated_ts": "1603589664673360",
"url": "https://ci.chromium.org/b/8865515224357405088",
"utcnow_ts": "1603589665343811"
}
}
[01:34:25.353] Invocation finished in 1h53m35.652048332s with status SUCCEEDED