[12:59:51.613] New invocation is queued and will start shortly
[12:59:52.733] Starting the invocation (attempt 1)
[12:59:52.773] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:59:52.775] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:59:52.775] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025912182524524224",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d2e226ee98c98c31a13b2c3e749c7e09d7072727\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9025912182524524224",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"gitiles_ref:refs/heads/master"
]
}
[12:59:53.408] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608382792867346",
"id": "8860482120545149840",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d2e226ee98c98c31a13b2c3e749c7e09d7072727\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608382793300958",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43865",
"builder:linux-chromeos-rel",
"buildset:commit/git/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025912182524524224",
"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/8860482120545149840/+/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": "1608382793301068",
"url": "https://ci.chromium.org/b/8860482120545149840",
"utcnow_ts": "1608382793401816"
}
}
[12:59:53.408] Task URL: https://ci.chromium.org/b/8860482120545149840
[12:59:53.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:2:0) after 1m0s
[13:00:53.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:2:0)
[13:00:53.442] Timer tick, asking Buildbucket for the build status
[13:00:53.575] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:53.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:3:0) after 1m0s
[13:01:53.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:3:0)
[13:01:53.595] Timer tick, asking Buildbucket for the build status
[13:01:53.696] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:53.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:4:0) after 1m0s
[13:02:53.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:4:0)
[13:02:53.716] Timer tick, asking Buildbucket for the build status
[13:02:53.803] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:53.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:5:0) after 1m0s
[13:03:53.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:5:0)
[13:03:53.815] Timer tick, asking Buildbucket for the build status
[13:03:53.871] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:53.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:6:0) after 1m0s
[13:04:53.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:6:0)
[13:04:53.929] Timer tick, asking Buildbucket for the build status
[13:04:54.423] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:54.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:7:0) after 1m0s
[13:05:54.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:7:0)
[13:05:54.453] Timer tick, asking Buildbucket for the build status
[13:05:54.549] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:8:0) after 1m0s
[13:06:54.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:8:0)
[13:06:54.622] Timer tick, asking Buildbucket for the build status
[13:06:54.957] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:54.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:9:0) after 1m0s
[13:07:54.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:9:0)
[13:07:54.976] Timer tick, asking Buildbucket for the build status
[13:07:55.110] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:55.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:10:0) after 1m0s
[13:08:55.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:10:0)
[13:08:55.118] Timer tick, asking Buildbucket for the build status
[13:08:55.249] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:55.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:11:0) after 1m0s
[13:09:55.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:11:0)
[13:09:55.319] Timer tick, asking Buildbucket for the build status
[13:09:55.723] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:55.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:12:0) after 1m0s
[13:10:55.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:12:0)
[13:10:55.793] Timer tick, asking Buildbucket for the build status
[13:10:55.895] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:55.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:13:0) after 1m0s
[13:11:56.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:13:0)
[13:11:56.029] Timer tick, asking Buildbucket for the build status
[13:11:56.095] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:14:0) after 1m0s
[13:12:56.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:14:0)
[13:12:56.125] Timer tick, asking Buildbucket for the build status
[13:12:56.359] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:56.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:15:0) after 1m0s
[13:13:56.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:15:0)
[13:13:56.853] Timer tick, asking Buildbucket for the build status
[13:13:56.931] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:56.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:16:0) after 1m0s
[13:14:57.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:16:0)
[13:14:57.260] Timer tick, asking Buildbucket for the build status
[13:14:57.357] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:57.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:17:0) after 1m0s
[13:15:57.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:17:0)
[13:15:57.587] Timer tick, asking Buildbucket for the build status
[13:15:57.775] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:57.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:18:0) after 1m0s
[13:16:57.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:18:0)
[13:16:57.812] Timer tick, asking Buildbucket for the build status
[13:16:57.875] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:57.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:19:0) after 1m0s
[13:17:58.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:19:0)
[13:17:58.014] Timer tick, asking Buildbucket for the build status
[13:17:58.077] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:58.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:20:0) after 1m0s
[13:18:58.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:20:0)
[13:18:58.313] Timer tick, asking Buildbucket for the build status
[13:18:58.383] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:58.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:21:0) after 1m0s
[13:19:58.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:21:0)
[13:19:58.555] Timer tick, asking Buildbucket for the build status
[13:19:58.674] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:58.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:22:0) after 1m0s
[13:20:58.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:22:0)
[13:20:58.723] Timer tick, asking Buildbucket for the build status
[13:20:58.801] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:58.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:23:0) after 1m0s
[13:21:58.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:23:0)
[13:21:58.995] Timer tick, asking Buildbucket for the build status
[13:21:59.078] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:59.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:24:0) after 1m0s
[13:22:59.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:24:0)
[13:22:59.225] Timer tick, asking Buildbucket for the build status
[13:22:59.310] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:25:0) after 1m0s
[13:23:59.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:25:0)
[13:23:59.464] Timer tick, asking Buildbucket for the build status
[13:23:59.528] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:59.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:26:0) after 1m0s
[13:24:59.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:26:0)
[13:24:59.856] Timer tick, asking Buildbucket for the build status
[13:25:00.423] Build 8860482120545149840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:00.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:27:0) after 1m0s
[13:25:26.522] Received PubSub notification, asking Buildbucket for the build status
[13:25:26.605] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:00.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:27:0)
[13:26:00.549] Timer tick, asking Buildbucket for the build status
[13:26:00.617] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:00.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:29:0) after 1m0s
[13:27:00.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:29:0)
[13:27:00.707] Timer tick, asking Buildbucket for the build status
[13:27:01.087] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:01.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:30:0) after 1m0s
[13:28:01.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:30:0)
[13:28:01.606] Timer tick, asking Buildbucket for the build status
[13:28:01.687] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:01.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:31:0) after 1m0s
[13:29:01.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:31:0)
[13:29:01.706] Timer tick, asking Buildbucket for the build status
[13:29:01.889] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:01.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:32:0) after 1m0s
[13:30:01.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:32:0)
[13:30:01.929] Timer tick, asking Buildbucket for the build status
[13:30:02.052] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:02.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:33:0) after 1m0s
[13:31:02.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:33:0)
[13:31:02.120] Timer tick, asking Buildbucket for the build status
[13:31:02.262] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:02.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:34:0) after 1m0s
[13:32:02.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:34:0)
[13:32:02.283] Timer tick, asking Buildbucket for the build status
[13:32:02.379] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:02.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:35:0) after 1m0s
[13:33:02.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:35:0)
[13:33:02.400] Timer tick, asking Buildbucket for the build status
[13:33:02.515] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:02.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:36:0) after 1m0s
[13:34:02.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:36:0)
[13:34:02.538] Timer tick, asking Buildbucket for the build status
[13:34:02.897] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:02.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:37:0) after 1m0s
[13:35:03.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:37:0)
[13:35:03.042] Timer tick, asking Buildbucket for the build status
[13:35:03.160] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:03.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:38:0) after 1m0s
[13:36:03.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:38:0)
[13:36:03.308] Timer tick, asking Buildbucket for the build status
[13:36:03.369] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:03.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:39:0) after 1m0s
[13:37:03.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:39:0)
[13:37:03.389] Timer tick, asking Buildbucket for the build status
[13:37:03.531] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:03.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:40:0) after 1m0s
[13:38:03.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:40:0)
[13:38:03.571] Timer tick, asking Buildbucket for the build status
[13:38:03.750] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:03.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:41:0) after 1m0s
[13:39:03.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:41:0)
[13:39:03.861] Timer tick, asking Buildbucket for the build status
[13:39:04.003] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:04.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:42:0) after 1m0s
[13:40:04.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:42:0)
[13:40:04.122] Timer tick, asking Buildbucket for the build status
[13:40:04.245] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:04.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:43:0) after 1m0s
[13:41:04.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:43:0)
[13:41:04.345] Timer tick, asking Buildbucket for the build status
[13:41:04.397] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:44:0) after 1m0s
[13:42:04.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:44:0)
[13:42:04.427] Timer tick, asking Buildbucket for the build status
[13:42:04.491] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:04.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:45:0) after 1m0s
[13:43:04.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:45:0)
[13:43:04.543] Timer tick, asking Buildbucket for the build status
[13:43:04.626] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:04.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:46:0) after 1m0s
[13:44:04.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:46:0)
[13:44:04.645] Timer tick, asking Buildbucket for the build status
[13:44:04.772] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:04.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:47:0) after 1m0s
[13:45:04.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:47:0)
[13:45:04.822] Timer tick, asking Buildbucket for the build status
[13:45:05.312] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:05.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:48:0) after 1m0s
[13:46:05.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:48:0)
[13:46:05.337] Timer tick, asking Buildbucket for the build status
[13:46:05.495] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:05.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:49:0) after 1m0s
[13:47:05.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:49:0)
[13:47:05.550] Timer tick, asking Buildbucket for the build status
[13:47:05.655] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:05.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:50:0) after 1m0s
[13:48:05.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:50:0)
[13:48:05.672] Timer tick, asking Buildbucket for the build status
[13:48:05.733] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:05.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:51:0) after 1m0s
[13:49:05.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:51:0)
[13:49:05.756] Timer tick, asking Buildbucket for the build status
[13:49:05.815] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:05.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:52:0) after 1m0s
[13:50:05.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:52:0)
[13:50:05.835] Timer tick, asking Buildbucket for the build status
[13:50:05.943] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:05.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:53:0) after 1m0s
[13:51:05.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:53:0)
[13:51:05.972] Timer tick, asking Buildbucket for the build status
[13:51:06.033] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:06.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:54:0) after 1m0s
[13:52:06.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:54:0)
[13:52:06.142] Timer tick, asking Buildbucket for the build status
[13:52:06.198] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:06.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:55:0) after 1m0s
[13:53:06.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:55:0)
[13:53:06.262] Timer tick, asking Buildbucket for the build status
[13:53:06.322] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:06.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:56:0) after 1m0s
[13:54:06.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:56:0)
[13:54:06.504] Timer tick, asking Buildbucket for the build status
[13:54:06.595] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:06.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:57:0) after 1m0s
[13:55:06.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:57:0)
[13:55:06.717] Timer tick, asking Buildbucket for the build status
[13:55:06.863] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:06.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:58:0) after 1m0s
[13:56:06.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:58:0)
[13:56:06.978] Timer tick, asking Buildbucket for the build status
[13:56:07.064] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:07.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:59:0) after 1m0s
[13:57:07.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:59:0)
[13:57:07.177] Timer tick, asking Buildbucket for the build status
[13:57:07.264] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:07.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:60:0) after 1m0s
[13:58:07.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:60:0)
[13:58:07.316] Timer tick, asking Buildbucket for the build status
[13:58:08.130] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:08.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:61:0) after 1m0s
[13:59:08.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:61:0)
[13:59:08.110] Timer tick, asking Buildbucket for the build status
[13:59:08.237] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:08.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:62:0) after 1m0s
[14:00:08.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:62:0)
[14:00:08.327] Timer tick, asking Buildbucket for the build status
[14:00:08.924] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:08.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:63:0) after 1m0s
[14:01:09.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:63:0)
[14:01:09.141] Timer tick, asking Buildbucket for the build status
[14:01:09.210] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:09.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:64:0) after 1m0s
[14:02:09.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:64:0)
[14:02:09.371] Timer tick, asking Buildbucket for the build status
[14:02:09.619] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:09.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:65:0) after 1m0s
[14:03:09.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:65:0)
[14:03:09.617] Timer tick, asking Buildbucket for the build status
[14:03:09.668] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:09.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:66:0) after 1m0s
[14:04:09.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:66:0)
[14:04:09.742] Timer tick, asking Buildbucket for the build status
[14:04:09.809] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:09.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:67:0) after 1m0s
[14:05:09.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:67:0)
[14:05:09.891] Timer tick, asking Buildbucket for the build status
[14:05:09.997] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:09.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:68:0) after 1m0s
[14:06:10.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:68:0)
[14:06:10.130] Timer tick, asking Buildbucket for the build status
[14:06:10.189] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:10.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:69:0) after 1m0s
[14:07:10.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:69:0)
[14:07:10.362] Timer tick, asking Buildbucket for the build status
[14:07:10.437] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:10.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:70:0) after 1m0s
[14:08:10.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:70:0)
[14:08:10.514] Timer tick, asking Buildbucket for the build status
[14:08:10.702] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:10.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:71:0) after 1m0s
[14:09:10.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:71:0)
[14:09:10.740] Timer tick, asking Buildbucket for the build status
[14:09:10.794] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:10.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:72:0) after 1m0s
[14:10:10.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:72:0)
[14:10:10.850] Timer tick, asking Buildbucket for the build status
[14:10:10.981] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:10.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:73:0) after 1m0s
[14:11:10.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:73:0)
[14:11:10.983] Timer tick, asking Buildbucket for the build status
[14:11:11.270] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:11.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:74:0) after 1m0s
[14:12:11.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:74:0)
[14:12:11.344] Timer tick, asking Buildbucket for the build status
[14:12:11.448] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:11.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:75:0) after 1m0s
[14:13:11.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:75:0)
[14:13:11.424] Timer tick, asking Buildbucket for the build status
[14:13:11.481] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:76:0) after 1m0s
[14:14:11.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:76:0)
[14:14:11.556] Timer tick, asking Buildbucket for the build status
[14:14:11.608] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:11.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:77:0) after 1m0s
[14:15:11.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:77:0)
[14:15:11.727] Timer tick, asking Buildbucket for the build status
[14:15:11.842] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:11.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:78:0) after 1m0s
[14:16:11.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:78:0)
[14:16:11.894] Timer tick, asking Buildbucket for the build status
[14:16:11.941] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:11.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:79:0) after 1m0s
[14:17:11.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:79:0)
[14:17:11.983] Timer tick, asking Buildbucket for the build status
[14:17:12.043] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:12.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:80:0) after 1m0s
[14:18:12.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:80:0)
[14:18:12.218] Timer tick, asking Buildbucket for the build status
[14:18:12.314] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:81:0) after 1m0s
[14:19:12.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:81:0)
[14:19:12.368] Timer tick, asking Buildbucket for the build status
[14:19:12.522] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:12.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:82:0) after 1m0s
[14:20:12.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:82:0)
[14:20:12.572] Timer tick, asking Buildbucket for the build status
[14:20:12.803] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:12.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:83:0) after 1m0s
[14:21:12.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:83:0)
[14:21:12.882] Timer tick, asking Buildbucket for the build status
[14:21:12.946] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:12.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:84:0) after 1m0s
[14:22:13.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:84:0)
[14:22:13.031] Timer tick, asking Buildbucket for the build status
[14:22:13.120] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:13.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:85:0) after 1m0s
[14:23:13.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:85:0)
[14:23:13.244] Timer tick, asking Buildbucket for the build status
[14:23:13.308] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:13.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:86:0) after 1m0s
[14:24:13.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:86:0)
[14:24:13.389] Timer tick, asking Buildbucket for the build status
[14:24:13.476] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:13.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:87:0) after 1m0s
[14:25:13.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:87:0)
[14:25:13.507] Timer tick, asking Buildbucket for the build status
[14:25:13.656] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:13.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:88:0) after 1m0s
[14:26:13.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:88:0)
[14:26:13.697] Timer tick, asking Buildbucket for the build status
[14:26:13.762] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:13.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:89:0) after 1m0s
[14:27:13.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:89:0)
[14:27:13.788] Timer tick, asking Buildbucket for the build status
[14:27:13.839] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:13.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:90:0) after 1m0s
[14:28:13.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:90:0)
[14:28:13.997] Timer tick, asking Buildbucket for the build status
[14:28:14.060] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:14.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:91:0) after 1m0s
[14:29:14.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:91:0)
[14:29:14.186] Timer tick, asking Buildbucket for the build status
[14:29:14.364] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:14.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:92:0) after 1m0s
[14:30:14.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:92:0)
[14:30:14.390] Timer tick, asking Buildbucket for the build status
[14:30:14.528] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:14.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:93:0) after 1m0s
[14:31:14.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:93:0)
[14:31:14.568] Timer tick, asking Buildbucket for the build status
[14:31:14.628] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:14.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:94:0) after 1m0s
[14:32:14.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:94:0)
[14:32:14.824] Timer tick, asking Buildbucket for the build status
[14:32:14.888] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:95:0) after 1m0s
[14:33:14.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:95:0)
[14:33:14.975] Timer tick, asking Buildbucket for the build status
[14:33:15.032] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:15.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:96:0) after 1m0s
[14:34:15.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:96:0)
[14:34:15.125] Timer tick, asking Buildbucket for the build status
[14:34:15.181] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:15.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:97:0) after 1m0s
[14:35:15.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:97:0)
[14:35:15.214] Timer tick, asking Buildbucket for the build status
[14:35:15.317] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:15.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:98:0) after 1m0s
[14:36:15.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:98:0)
[14:36:15.338] Timer tick, asking Buildbucket for the build status
[14:36:15.506] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:15.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:99:0) after 1m0s
[14:37:15.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:99:0)
[14:37:15.572] Timer tick, asking Buildbucket for the build status
[14:37:15.631] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:15.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:100:0) after 1m0s
[14:38:15.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:100:0)
[14:38:15.632] Timer tick, asking Buildbucket for the build status
[14:38:15.737] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:15.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:101:0) after 1m0s
[14:39:15.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:101:0)
[14:39:15.833] Timer tick, asking Buildbucket for the build status
[14:39:15.962] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:15.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:102:0) after 1m0s
[14:40:16.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:102:0)
[14:40:16.056] Timer tick, asking Buildbucket for the build status
[14:40:16.148] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:16.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:103:0) after 1m0s
[14:41:16.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:103:0)
[14:41:16.224] Timer tick, asking Buildbucket for the build status
[14:41:16.317] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:16.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:104:0) after 1m0s
[14:42:16.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:104:0)
[14:42:16.392] Timer tick, asking Buildbucket for the build status
[14:42:17.021] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:17.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:105:0) after 1m0s
[14:43:17.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:105:0)
[14:43:17.085] Timer tick, asking Buildbucket for the build status
[14:43:17.227] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:17.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:106:0) after 1m0s
[14:44:17.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:106:0)
[14:44:17.309] Timer tick, asking Buildbucket for the build status
[14:44:17.472] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:17.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:107:0) after 1m0s
[14:45:17.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:107:0)
[14:45:17.513] Timer tick, asking Buildbucket for the build status
[14:45:18.227] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:18.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:108:0) after 1m0s
[14:46:18.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:108:0)
[14:46:18.252] Timer tick, asking Buildbucket for the build status
[14:46:18.365] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:109:0) after 1m0s
[14:47:18.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:109:0)
[14:47:18.539] Timer tick, asking Buildbucket for the build status
[14:47:18.656] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:18.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:110:0) after 1m0s
[14:48:18.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:110:0)
[14:48:18.698] Timer tick, asking Buildbucket for the build status
[14:48:18.974] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:18.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:111:0) after 1m0s
[14:49:18.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:111:0)
[14:49:18.972] Timer tick, asking Buildbucket for the build status
[14:49:19.126] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:19.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:112:0) after 1m0s
[14:50:19.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:112:0)
[14:50:19.205] Timer tick, asking Buildbucket for the build status
[14:50:19.676] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:19.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:113:0) after 1m0s
[14:51:19.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:113:0)
[14:51:19.714] Timer tick, asking Buildbucket for the build status
[14:51:19.787] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:19.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:114:0) after 1m0s
[14:52:19.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:114:0)
[14:52:19.860] Timer tick, asking Buildbucket for the build status
[14:52:19.962] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:19.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:115:0) after 1m0s
[14:53:19.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:115:0)
[14:53:19.975] Timer tick, asking Buildbucket for the build status
[14:53:20.124] Build 8860482120545149840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:20.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9025912182524524224:116:0) after 1m0s
[14:54:09.226] Received PubSub notification, asking Buildbucket for the build status
[14:54:09.318] Build 8860482120545149840: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:54:09.318] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608389648314127",
"created_by": "project:chromium",
"created_ts": "1608382792867346",
"id": "8860482120545149840",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d2e226ee98c98c31a13b2c3e749c7e09d7072727\"}}",
"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\": \"d2e226ee98c98c31a13b2c3e749c7e09d7072727\", \"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-57-v74m\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43865, \"got_angle_revision\": \"011e0ad18679541b98b1d83bd86fd70bc7ea5664\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"d2e226ee98c98c31a13b2c3e749c7e09d7072727\", \"got_revision_cp\": \"refs/heads/master@{#838517}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"7d77ba8cf62c0f7a6f955f2da793814ba3f52d54\", \"got_v8_revision_cp\": \"refs/heads/8.9.176@{#1}\", \"got_webrtc_revision\": \"d7f750f7be4b2f61018d3cb55a995d09daa226e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32862}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d2e226ee98c98c31a13b2c3e749c7e09d7072727\", \"swarm_hashes_refs/heads/master(at){#838517}_without_patch\": {\"absl_hardening_tests\": \"e1f8271316b538c1e72205efb98dc4c9a76720b8\", \"accessibility_unittests\": \"55b8e47353eeb42021d5eefad03c8e954003c310\", \"angle_unittests\": \"864b34ba8b957c64fc8f1b53aaccb523860da1a2\", \"app_list_unittests\": \"8c201f127741040998016ef804ca3fa9e70b4c14\", \"app_shell_unittests\": \"902834af3cac0c19a6528ba247e60ad28b79959f\", \"ash_unittests\": \"0b91650aa56055a02b53c46d60cc1be84cc466e0\", \"aura_unittests\": \"2a8db3ea3b244d314f3a1177383dd061dcede692\", \"base_unittests\": \"915ab7426ac8279a9e6d23fa5d8a605cc6c16597\", \"base_util_unittests\": \"6060ab75e4f7c7b55a2ec2aa9d19fe84dd162112\", \"blink_common_unittests\": \"e2f671e36e6d61f59c17eeb7ee2bc11f43ad06ad\", \"blink_fuzzer_unittests\": \"eb107b3e7eb3ec494aa19d481969143751031c5b\", \"blink_heap_unittests\": \"ac70a855ed58f10c7e9b4d4ce5d59b13250b4e3a\", \"blink_platform_unittests\": \"7a8c54ce11c01d54bec5ea7c7dd6a4b825d01ad2\", \"blink_unittests\": \"4cc192ea2b9cf36206d52227f7da38b411407b4b\", \"boringssl_crypto_tests\": \"492d1808719ccdea1d8500cbadfa50fa3ea72980\", \"boringssl_ssl_tests\": \"5d1c9dcf0a0a208888fb890b4f8e94f769223736\", \"browser_tests\": \"f9c25a697381dff8b16b8718b7349019c2b3d287\", \"capture_unittests\": \"f14e29fc838a59e09e1fd36e608e148c002c51fe\", \"cast_unittests\": \"43f617cab947f03081ab13e741761c918c8eec95\", \"cc_unittests\": \"1ec59d4e0bb3eb214a0e328a7522b643980c534a\", \"chrome_app_unittests\": \"86f57551d605b71a654ced1ff959cc0e8f7769f2\", \"chromedriver_unittests\": \"0e0b0a59f7a82c8ca7bd1fccc32544cb76a2f16c\", \"chromeos_components_unittests\": \"237dd3b0dfd63498827d27c677696e99f4b457a0\", \"chromeos_unittests\": \"8d23b78e6e4f6a27a0c47ab00d2e725c71c2c2b0\", \"components_browsertests\": \"e8d3e0e1deeaa481e34fc7a1be2bc18c8408f699\", \"components_unittests\": \"6ae37127ed8c1db7762045741dcacf0593f9ccd6\", \"compositor_unittests\": \"7f85acc91372f61332f9f37db53d4037b7ecc533\", \"content_browsertests\": \"7bbf309fdd0d408c901c5e81bc8967740ef2f945\", \"content_unittests\": \"2f51aaa034a11a544a4235c69ce3ccc380541427\", \"crashpad_tests\": \"c19d47fb5cdb97bad3696b8d866acb592a6502f8\", \"crypto_unittests\": \"aa071168f5b70c5013e1ffcf10c87be77c9eced5\", \"dbus_unittests\": \"0d09171f83e21ca1f98d4201ba9cfa04eb107e68\", \"device_unittests\": \"8ef38c68f6122b7580834bee8998cc0a5ba32fc8\", \"display_unittests\": \"3a10b79b708e6da95aa84fbe877e1b5d0f760969\", \"events_unittests\": \"9ec486b47bc8159efd160446f4af2066f7bf9fb9\", \"exo_unittests\": \"5aa39d382e1cec16ab28fa1e4188367c8a709b8c\", \"extensions_browsertests\": \"2071d0e8fd42b24667cfa1a0ad45e98469df1de9\", \"extensions_unittests\": \"f196c464338c84ce88a088938c3c3c5485d89810\", \"filesystem_service_unittests\": \"0329c3d9407ddeda063013cb726ce99b47d065ab\", \"gcm_unit_tests\": \"d3c19cad6afaf4489ffdbef6ee0d4b4cbd8c979b\", \"gfx_unittests\": \"c9f0105c792436215747e2153182b798a5287563\", \"gin_unittests\": \"ec0c50be7faf81ae1cda15020bfe4113d1e3136f\", \"gl_unittests_ozone\": \"022ef2bcb21823667cb7cd836f346bc0896e5c52\", \"google_apis_unittests\": \"ff26ebcca59bfb46a3f4054147bc79aca1567ea4\", \"gpu_unittests\": \"1d1cff1636650414adbae87f8cd57ea177571edf\", \"gwp_asan_unittests\": \"6301e7c4bd0bdbf1e4b0b0db2f52ed0a3ff9a4b6\", \"interactive_ui_tests\": \"fb696c071af1d347efa705491283e250d80902d7\", \"ipc_tests\": \"e52ad1f1ffd5a0f09eeee5799ee85ece51d9c885\", \"jingle_unittests\": \"be46139dd269e163754039723ed3ac8957361a3a\", \"keyboard_unittests\": \"3d8f17463b395576a0f59bac985b9df198ec140a\", \"latency_unittests\": \"8ef2fe9a561b46bf1ca11a69fdb399ac9951efba\", \"libjingle_xmpp_unittests\": \"de0e8967d152e8dac836bc35cffd15b465d18715\", \"liburlpattern_unittests\": \"ee885ac1517be436a014f9bbb00d03a00e92450e\", \"media_blink_unittests\": \"52b65c4b1062e907f1e3048d11cbda26c25b9db9\", \"media_unittests\": \"b31d5514bea5d9aacc35e9f93cbe2d8abe1f8179\", \"message_center_unittests\": \"3ddffe61b38343734ade31444d2f61dc78fbc867\", \"midi_unittests\": \"2147de8961dd3af310c06e26e36c1092bba3e7f0\", \"mojo_core_unittests\": \"f4ac6243e276bde99cb3165ec6903fd6f5133ab9\", \"mojo_unittests\": \"aaf28427df185a973f320500ca72814db3129563\", \"nacl_helper_nonsfi_unittests\": \"1278771e0da2640c19ddc1946252dca199efa611\", \"nacl_loader_unittests\": \"49308398973a5365d508f611d70e51a8e8280ac7\", \"native_theme_unittests\": \"6b8a1a20df765257c8a7787a3c8747e89ee23299\", \"net_unittests\": \"cdb1644e1f5725d0560b3f399d388ad46fb2084b\", \"ozone_gl_unittests\": \"ec0b1d5ba14f459b8d367582a1caa477ebbf6c6d\", \"ozone_unittests\": \"5372213071790393753b0e25f52d3ade01dda352\", \"ozone_x11_unittests\": \"392e5f1a62f06a0e96ea1d5e918b3e4388e28108\", \"pdf_unittests\": \"963d157930679cc1ee15fcdfa38b70f1637fa978\", \"perfetto_unittests\": \"430df414db31b199242ca611994df17af7849a0f\", \"ppapi_unittests\": \"28d8d19c4e40c5ab37777a5c7702a0c5c5c86a7a\", \"printing_unittests\": \"ec2dee37e44cdbd8490f499444a3e45bb69188d9\", \"remoting_unittests\": \"d2a49170c61cf29271b13f926c1506825741d85c\", \"sandbox_linux_unittests\": \"f937d763dbda45a9f8c15816e017883026945641\", \"service_manager_unittests\": \"ef7addd9bd8b9248c65f88cd14c5951043d35958\", \"services_unittests\": \"80666ce74b4223fd1c50a70dea66bc514e5b16ed\", \"shell_dialogs_unittests\": \"1cac0189251e660e1d5989395bb4d96f06108a6e\", \"shell_encryption_unittests\": \"48b1df624cbd00a5817da1ff3538611fa5c607a2\", \"skia_unittests\": \"8ad73d8d1b3594015a89172c317fc94616a8d2d1\", \"snapshot_unittests\": \"67db46eb56b68d345da98e9368df4e690fb573a5\", \"sql_unittests\": \"0971cd3e5e903e5d7ef3f55dac5e3ab80fd13a53\", \"storage_unittests\": \"3bce35c9900a59f4c91a7bdcc1344b447e77db78\", \"sync_integration_tests\": \"ec60077c4bb64fff606761633c491192f3b8a892\", \"traffic_annotation_auditor_unittests\": \"c82ff6c1167bc86cfcc0b5e34e24d61cc9504a44\", \"ui_base_unittests\": \"bb53e0816677ed126d8d03263627840229431d11\", \"ui_chromeos_unittests\": \"63f775a037a2b650bd075ac3a073ef6cadfedcca\", \"ui_touch_selection_unittests\": \"86323d8a5bacd52538352b10b82139d556f9bce4\", \"unit_tests\": \"1bec665d25a588c9e1fbc5ad9e8267522d07d58e\", \"url_unittests\": \"d61d7647a046d071608357b077281171dce16305\", \"usage_time_limit_unittests\": \"3bef725a4e07b8be88d7d875c82a6a347f91370b\", \"views_unittests\": \"7b44f2fca71a9e7cc9112408f630b123a1b9cb8d\", \"viz_unittests\": \"c56a541b05c8ba78991d7a5fc5bac5b6c1791836\", \"wayland_client_perftests\": \"2a9f8d4a0a59eea56c79eb9bdb32b7d6d639ca7d\", \"wm_unittests\": \"1e9c34f5c8c38e02ead3aebd550da7881e614474\", \"wtf_unittests\": \"0d46c75ed2d3a2516aa1166076c4fa1e1898fd1d\", \"zlib_unittests\": \"98a4a8aab301ffb8fbad8cf055bfd3e989dee904\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2924decd8bf92d66d12e0f7871729cd28b590c544e302b2201ef1e5b1ad96b76_v2\", \"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_3ff20984f80f48d33e2b03ffc12bed5306b6d9761e5e01dd0c6daff69f57abc3_v2\", \"builder_7b27d20986eac2bbcc1513665f8799c69dbe398236c21423fd80a70475184e3c_v2\", \"builder_8d3964f240d2ac33279430ad9dd2ee384a3154a98873fec61a3662901a9c4b2a_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_d6c402558de63915dde657133f192d995a5b729212ea1587a5d59fbce371e332_v2\", \"builder_fb646d86e88bda56572dff3378a9fa669f47f3a45e650c7783bf21742e524b22_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-57-v74m\"], \"image\": [\"chrome-xenial-20121300-d43316a0aad\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608384325737645",
"status": "COMPLETED",
"status_changed_ts": "1608389648693397",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43865",
"builder:linux-chromeos-rel",
"buildset:commit/git/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d2e226ee98c98c31a13b2c3e749c7e09d7072727",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025912182524524224",
"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/8860482120545149840/+/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:5093e7501f73f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1608389648698442",
"url": "https://ci.chromium.org/b/8860482120545149840",
"utcnow_ts": "1608389649308218"
}
}
[14:54:09.318] Invocation finished in 1h54m17.72108597s with status SUCCEEDED