[03:56:21.834] New invocation is queued and will start shortly
[03:56:23.449] Starting the invocation (attempt 1)
[03:56:23.485] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:56:23.485] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:56:23.485] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022141303766154352",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3c34a12d7dec4008142b2a252b13339ed2fa38f0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022141303766154352",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"gitiles_ref:refs/heads/master"
]
}
[03:56:24.245] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611978983570212",
"id": "8856711241282309072",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c34a12d7dec4008142b2a252b13339ed2fa38f0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611978983995384",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45017",
"builder:linux-chromeos-rel",
"buildset:commit/git/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022141303766154352",
"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/8856711241282309072/+/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": "1611978983995641",
"url": "https://ci.chromium.org/b/8856711241282309072",
"utcnow_ts": "1611978984216376"
}
}
[03:56:24.245] Task URL: https://ci.chromium.org/b/8856711241282309072
[03:56:24.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:2:0) after 1m0s
[03:57:24.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:2:0)
[03:57:24.259] Timer tick, asking Buildbucket for the build status
[03:57:24.762] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:24.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:3:0) after 1m0s
[03:58:24.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:3:0)
[03:58:24.837] Timer tick, asking Buildbucket for the build status
[03:58:25.101] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:25.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:4:0) after 1m0s
[03:59:25.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:4:0)
[03:59:25.853] Timer tick, asking Buildbucket for the build status
[03:59:25.926] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:25.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:5:0) after 1m0s
[04:00:26.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:5:0)
[04:00:26.104] Timer tick, asking Buildbucket for the build status
[04:00:26.185] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:26.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:6:0) after 1m0s
[04:01:26.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:6:0)
[04:01:26.416] Timer tick, asking Buildbucket for the build status
[04:01:27.122] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:27.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:7:0) after 1m0s
[04:02:27.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:7:0)
[04:02:27.250] Timer tick, asking Buildbucket for the build status
[04:02:27.324] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:27.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:8:0) after 1m0s
[04:03:27.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:8:0)
[04:03:27.502] Timer tick, asking Buildbucket for the build status
[04:03:27.584] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:27.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:9:0) after 1m0s
[04:04:27.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:9:0)
[04:04:27.583] Timer tick, asking Buildbucket for the build status
[04:04:27.710] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:27.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:10:0) after 1m0s
[04:05:27.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:10:0)
[04:05:27.693] Timer tick, asking Buildbucket for the build status
[04:05:27.763] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:27.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:11:0) after 1m0s
[04:06:27.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:11:0)
[04:06:27.798] Timer tick, asking Buildbucket for the build status
[04:06:29.197] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:12:0) after 1m0s
[04:07:29.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:12:0)
[04:07:29.191] Timer tick, asking Buildbucket for the build status
[04:07:29.267] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:29.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:13:0) after 1m0s
[04:08:29.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:13:0)
[04:08:29.311] Timer tick, asking Buildbucket for the build status
[04:08:29.387] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:29.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:14:0) after 1m0s
[04:09:29.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:14:0)
[04:09:29.498] Timer tick, asking Buildbucket for the build status
[04:09:29.762] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:29.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:15:0) after 1m0s
[04:10:29.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:15:0)
[04:10:29.779] Timer tick, asking Buildbucket for the build status
[04:10:29.866] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:29.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:16:0) after 1m0s
[04:11:29.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:16:0)
[04:11:29.911] Timer tick, asking Buildbucket for the build status
[04:11:30.025] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:30.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:17:0) after 1m0s
[04:12:30.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:17:0)
[04:12:30.075] Timer tick, asking Buildbucket for the build status
[04:12:30.209] Build 8856711241282309072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:30.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:18:0) after 1m0s
[04:13:04.572] Received PubSub notification, asking Buildbucket for the build status
[04:13:04.939] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:30.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:18:0)
[04:13:30.288] Timer tick, asking Buildbucket for the build status
[04:13:30.381] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:30.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:20:0) after 1m0s
[04:14:30.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:20:0)
[04:14:30.400] Timer tick, asking Buildbucket for the build status
[04:14:30.515] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:30.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:21:0) after 1m0s
[04:15:30.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:21:0)
[04:15:30.604] Timer tick, asking Buildbucket for the build status
[04:15:30.683] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:30.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:22:0) after 1m0s
[04:16:30.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:22:0)
[04:16:30.743] Timer tick, asking Buildbucket for the build status
[04:16:30.882] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:23:0) after 1m0s
[04:17:30.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:23:0)
[04:17:30.936] Timer tick, asking Buildbucket for the build status
[04:17:30.987] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:24:0) after 1m0s
[04:18:31.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:24:0)
[04:18:31.082] Timer tick, asking Buildbucket for the build status
[04:18:31.192] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:31.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:25:0) after 1m0s
[04:19:31.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:25:0)
[04:19:31.293] Timer tick, asking Buildbucket for the build status
[04:19:31.825] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:31.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:26:0) after 1m0s
[04:20:31.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:26:0)
[04:20:31.904] Timer tick, asking Buildbucket for the build status
[04:20:31.963] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:31.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:27:0) after 1m0s
[04:21:32.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:27:0)
[04:21:32.067] Timer tick, asking Buildbucket for the build status
[04:21:32.176] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:32.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:28:0) after 1m0s
[04:22:32.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:28:0)
[04:22:32.191] Timer tick, asking Buildbucket for the build status
[04:22:32.416] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:32.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:29:0) after 1m0s
[04:23:32.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:29:0)
[04:23:32.527] Timer tick, asking Buildbucket for the build status
[04:23:32.897] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:32.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:30:0) after 1m0s
[04:24:32.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:30:0)
[04:24:32.947] Timer tick, asking Buildbucket for the build status
[04:24:33.151] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:33.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:31:0) after 1m0s
[04:25:33.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:31:0)
[04:25:33.200] Timer tick, asking Buildbucket for the build status
[04:25:33.389] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:33.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:32:0) after 1m0s
[04:26:33.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:32:0)
[04:26:33.411] Timer tick, asking Buildbucket for the build status
[04:26:33.523] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:33.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:33:0) after 1m0s
[04:27:33.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:33:0)
[04:27:33.523] Timer tick, asking Buildbucket for the build status
[04:27:33.895] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:33.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:34:0) after 1m0s
[04:28:33.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:34:0)
[04:28:33.922] Timer tick, asking Buildbucket for the build status
[04:28:34.312] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:34.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:35:0) after 1m0s
[04:29:34.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:35:0)
[04:29:34.319] Timer tick, asking Buildbucket for the build status
[04:29:34.377] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:34.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:36:0) after 1m0s
[04:30:34.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:36:0)
[04:30:34.424] Timer tick, asking Buildbucket for the build status
[04:30:34.515] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:34.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:37:0) after 1m0s
[04:31:34.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:37:0)
[04:31:34.535] Timer tick, asking Buildbucket for the build status
[04:31:34.700] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:34.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:38:0) after 1m0s
[04:32:34.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:38:0)
[04:32:34.721] Timer tick, asking Buildbucket for the build status
[04:32:35.035] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:35.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:39:0) after 1m0s
[04:33:35.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:39:0)
[04:33:35.143] Timer tick, asking Buildbucket for the build status
[04:33:35.213] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:35.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:40:0) after 1m0s
[04:34:35.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:40:0)
[04:34:35.286] Timer tick, asking Buildbucket for the build status
[04:34:35.347] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:35.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:41:0) after 1m0s
[04:35:35.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:41:0)
[04:35:35.390] Timer tick, asking Buildbucket for the build status
[04:35:35.706] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:35.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:42:0) after 1m0s
[04:36:35.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:42:0)
[04:36:35.776] Timer tick, asking Buildbucket for the build status
[04:36:35.921] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:35.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:43:0) after 1m0s
[04:37:35.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:43:0)
[04:37:35.957] Timer tick, asking Buildbucket for the build status
[04:37:36.207] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:44:0) after 1m0s
[04:38:36.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:44:0)
[04:38:36.224] Timer tick, asking Buildbucket for the build status
[04:38:36.402] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:36.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:45:0) after 1m0s
[04:39:36.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:45:0)
[04:39:36.439] Timer tick, asking Buildbucket for the build status
[04:39:36.504] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:36.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:46:0) after 1m0s
[04:40:36.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:46:0)
[04:40:36.551] Timer tick, asking Buildbucket for the build status
[04:40:36.702] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:36.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:47:0) after 1m0s
[04:41:36.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:47:0)
[04:41:36.828] Timer tick, asking Buildbucket for the build status
[04:41:36.937] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:48:0) after 1m0s
[04:42:36.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:48:0)
[04:42:36.958] Timer tick, asking Buildbucket for the build status
[04:42:37.022] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:37.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:49:0) after 1m0s
[04:43:37.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:49:0)
[04:43:37.083] Timer tick, asking Buildbucket for the build status
[04:43:37.141] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:50:0) after 1m0s
[04:44:37.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:50:0)
[04:44:37.213] Timer tick, asking Buildbucket for the build status
[04:44:37.278] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:51:0) after 1m0s
[04:45:37.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:51:0)
[04:45:37.295] Timer tick, asking Buildbucket for the build status
[04:45:37.359] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:37.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:52:0) after 1m0s
[04:46:37.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:52:0)
[04:46:37.496] Timer tick, asking Buildbucket for the build status
[04:46:37.587] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:37.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:53:0) after 1m0s
[04:47:37.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:53:0)
[04:47:37.640] Timer tick, asking Buildbucket for the build status
[04:47:37.839] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:37.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:54:0) after 1m0s
[04:48:37.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:54:0)
[04:48:37.827] Timer tick, asking Buildbucket for the build status
[04:48:37.893] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:55:0) after 1m0s
[04:49:37.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:55:0)
[04:49:37.995] Timer tick, asking Buildbucket for the build status
[04:49:38.062] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:38.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:56:0) after 1m0s
[04:50:38.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:56:0)
[04:50:38.112] Timer tick, asking Buildbucket for the build status
[04:50:38.187] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:38.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:57:0) after 1m0s
[04:51:38.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:57:0)
[04:51:38.234] Timer tick, asking Buildbucket for the build status
[04:51:38.346] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:38.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:58:0) after 1m0s
[04:52:38.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:58:0)
[04:52:38.365] Timer tick, asking Buildbucket for the build status
[04:52:38.435] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:38.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:59:0) after 1m0s
[04:53:38.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:59:0)
[04:53:38.531] Timer tick, asking Buildbucket for the build status
[04:53:38.611] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:38.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:60:0) after 1m0s
[04:54:38.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:60:0)
[04:54:38.676] Timer tick, asking Buildbucket for the build status
[04:54:38.852] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:38.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:61:0) after 1m0s
[04:55:38.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:61:0)
[04:55:38.862] Timer tick, asking Buildbucket for the build status
[04:55:39.047] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:62:0) after 1m0s
[04:56:39.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:62:0)
[04:56:39.376] Timer tick, asking Buildbucket for the build status
[04:56:39.505] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:39.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:63:0) after 1m0s
[04:57:39.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:63:0)
[04:57:39.577] Timer tick, asking Buildbucket for the build status
[04:57:39.694] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:39.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:64:0) after 1m0s
[04:58:39.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:64:0)
[04:58:39.794] Timer tick, asking Buildbucket for the build status
[04:58:39.972] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:39.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:65:0) after 1m0s
[04:59:40.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:65:0)
[04:59:40.179] Timer tick, asking Buildbucket for the build status
[04:59:40.243] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:40.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:66:0) after 1m0s
[05:00:40.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:66:0)
[05:00:40.374] Timer tick, asking Buildbucket for the build status
[05:00:40.482] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:40.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:67:0) after 1m0s
[05:01:40.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:67:0)
[05:01:40.623] Timer tick, asking Buildbucket for the build status
[05:01:40.778] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:40.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:68:0) after 1m0s
[05:02:40.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:68:0)
[05:02:40.805] Timer tick, asking Buildbucket for the build status
[05:02:41.015] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:41.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:69:0) after 1m0s
[05:03:41.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:69:0)
[05:03:41.021] Timer tick, asking Buildbucket for the build status
[05:03:41.082] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:41.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:70:0) after 1m0s
[05:04:41.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:70:0)
[05:04:41.197] Timer tick, asking Buildbucket for the build status
[05:04:41.409] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:71:0) after 1m0s
[05:05:41.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:71:0)
[05:05:41.491] Timer tick, asking Buildbucket for the build status
[05:05:41.571] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:41.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:72:0) after 1m0s
[05:06:41.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:72:0)
[05:06:41.656] Timer tick, asking Buildbucket for the build status
[05:06:41.802] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:41.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:73:0) after 1m0s
[05:07:41.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:73:0)
[05:07:41.919] Timer tick, asking Buildbucket for the build status
[05:07:41.976] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:41.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:74:0) after 1m0s
[05:08:42.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:74:0)
[05:08:42.001] Timer tick, asking Buildbucket for the build status
[05:08:42.078] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:75:0) after 1m0s
[05:09:42.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:75:0)
[05:09:42.061] Timer tick, asking Buildbucket for the build status
[05:09:42.167] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:42.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:76:0) after 1m0s
[05:10:42.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:76:0)
[05:10:42.192] Timer tick, asking Buildbucket for the build status
[05:10:42.275] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:42.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:77:0) after 1m0s
[05:11:42.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:77:0)
[05:11:42.307] Timer tick, asking Buildbucket for the build status
[05:11:42.409] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:42.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:78:0) after 1m0s
[05:12:42.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:78:0)
[05:12:42.488] Timer tick, asking Buildbucket for the build status
[05:12:42.545] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:42.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:79:0) after 1m0s
[05:13:42.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:79:0)
[05:13:42.641] Timer tick, asking Buildbucket for the build status
[05:13:42.707] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:80:0) after 1m0s
[05:14:42.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:80:0)
[05:14:42.753] Timer tick, asking Buildbucket for the build status
[05:14:42.817] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:42.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:81:0) after 1m0s
[05:15:42.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:81:0)
[05:15:42.879] Timer tick, asking Buildbucket for the build status
[05:15:43.128] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:43.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:82:0) after 1m0s
[05:16:43.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:82:0)
[05:16:43.181] Timer tick, asking Buildbucket for the build status
[05:16:43.502] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:43.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:83:0) after 1m0s
[05:17:43.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:83:0)
[05:17:43.522] Timer tick, asking Buildbucket for the build status
[05:17:43.581] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:43.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:84:0) after 1m0s
[05:18:43.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:84:0)
[05:18:43.637] Timer tick, asking Buildbucket for the build status
[05:18:43.897] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:43.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:85:0) after 1m0s
[05:19:43.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:85:0)
[05:19:43.882] Timer tick, asking Buildbucket for the build status
[05:19:43.972] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:43.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:86:0) after 1m0s
[05:20:43.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:86:0)
[05:20:43.999] Timer tick, asking Buildbucket for the build status
[05:20:44.062] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:44.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:87:0) after 1m0s
[05:21:44.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:87:0)
[05:21:44.139] Timer tick, asking Buildbucket for the build status
[05:21:44.266] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:44.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:88:0) after 1m0s
[05:22:44.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:88:0)
[05:22:44.291] Timer tick, asking Buildbucket for the build status
[05:22:44.356] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:44.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:89:0) after 1m0s
[05:23:44.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:89:0)
[05:23:44.366] Timer tick, asking Buildbucket for the build status
[05:23:44.445] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:44.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:90:0) after 1m0s
[05:24:44.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:90:0)
[05:24:44.471] Timer tick, asking Buildbucket for the build status
[05:24:44.557] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:44.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:91:0) after 1m0s
[05:25:44.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:91:0)
[05:25:44.584] Timer tick, asking Buildbucket for the build status
[05:25:44.642] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:44.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:92:0) after 1m0s
[05:26:44.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:92:0)
[05:26:44.696] Timer tick, asking Buildbucket for the build status
[05:26:44.829] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:44.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:93:0) after 1m0s
[05:27:44.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:93:0)
[05:27:44.853] Timer tick, asking Buildbucket for the build status
[05:27:44.918] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:44.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:94:0) after 1m0s
[05:28:44.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:94:0)
[05:28:44.927] Timer tick, asking Buildbucket for the build status
[05:28:44.990] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:44.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:95:0) after 1m0s
[05:29:45.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:95:0)
[05:29:45.015] Timer tick, asking Buildbucket for the build status
[05:29:45.087] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:45.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:96:0) after 1m0s
[05:30:45.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:96:0)
[05:30:45.164] Timer tick, asking Buildbucket for the build status
[05:30:45.428] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:45.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:97:0) after 1m0s
[05:31:45.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:97:0)
[05:31:45.488] Timer tick, asking Buildbucket for the build status
[05:31:46.228] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:46.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:98:0) after 1m0s
[05:32:46.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:98:0)
[05:32:46.352] Timer tick, asking Buildbucket for the build status
[05:32:46.456] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:46.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:99:0) after 1m0s
[05:33:46.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:99:0)
[05:33:46.485] Timer tick, asking Buildbucket for the build status
[05:33:46.567] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:46.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:100:0) after 1m0s
[05:34:46.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:100:0)
[05:34:46.677] Timer tick, asking Buildbucket for the build status
[05:34:46.770] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:46.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:101:0) after 1m0s
[05:35:46.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:101:0)
[05:35:46.827] Timer tick, asking Buildbucket for the build status
[05:35:47.009] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:47.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:102:0) after 1m0s
[05:36:47.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:102:0)
[05:36:47.039] Timer tick, asking Buildbucket for the build status
[05:36:47.596] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:47.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:103:0) after 1m0s
[05:37:47.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:103:0)
[05:37:47.619] Timer tick, asking Buildbucket for the build status
[05:37:47.906] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:47.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:104:0) after 1m0s
[05:38:47.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:104:0)
[05:38:47.942] Timer tick, asking Buildbucket for the build status
[05:38:48.052] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:48.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:105:0) after 1m0s
[05:39:48.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:105:0)
[05:39:48.079] Timer tick, asking Buildbucket for the build status
[05:39:48.200] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:48.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:106:0) after 1m0s
[05:40:48.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:106:0)
[05:40:48.238] Timer tick, asking Buildbucket for the build status
[05:40:48.443] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:48.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:107:0) after 1m0s
[05:41:48.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:107:0)
[05:41:48.462] Timer tick, asking Buildbucket for the build status
[05:41:48.571] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:108:0) after 1m0s
[05:42:48.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:108:0)
[05:42:48.625] Timer tick, asking Buildbucket for the build status
[05:42:48.755] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:48.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:109:0) after 1m0s
[05:43:48.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:109:0)
[05:43:48.818] Timer tick, asking Buildbucket for the build status
[05:43:48.940] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:48.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:110:0) after 1m0s
[05:44:49.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:110:0)
[05:44:49.021] Timer tick, asking Buildbucket for the build status
[05:44:49.405] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:49.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:111:0) after 1m0s
[05:45:49.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:111:0)
[05:45:49.508] Timer tick, asking Buildbucket for the build status
[05:45:50.364] Build 8856711241282309072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:50.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022141303766154352:112:0) after 1m0s
[05:46:16.280] Received PubSub notification, asking Buildbucket for the build status
[05:46:16.367] Build 8856711241282309072: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:46:16.367] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611985575316140",
"created_by": "project:chromium",
"created_ts": "1611978983570212",
"failure_reason": "BUILD_FAILURE",
"id": "8856711241282309072",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c34a12d7dec4008142b2a252b13339ed2fa38f0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- EulaTest.AdditionalToS\"}, \"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\": \"3c34a12d7dec4008142b2a252b13339ed2fa38f0\", \"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-61-vlgi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45017, \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"3c34a12d7dec4008142b2a252b13339ed2fa38f0\", \"got_revision_cp\": \"refs/heads/master@{#848865}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"f9a6148e0498abdbe74eb6edf6aac78667d29499\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33109}\", \"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\": \"3c34a12d7dec4008142b2a252b13339ed2fa38f0\", \"swarm_hashes_refs/heads/master(at){#848865}_without_patch\": {\"absl_hardening_tests\": \"46585d3a2ae5551bd7391ea675118a527f3d2920\", \"accessibility_unittests\": \"ce8ab60770a33f952c4688d4b2658d4820d134f7\", \"angle_unittests\": \"92f4861701cb6257397ef4adb9924d8d67d01616\", \"app_list_unittests\": \"49b83f17f210030900e6085a37273412244156b9\", \"app_shell_unittests\": \"c090661b2d8864a3ccf5e261871ea406a30d16a3\", \"ash_unittests\": \"ea4c21b71a90fdc2ef78830907eb031a2b19d758\", \"aura_unittests\": \"1b0fb6714b172061e1e3fc76da0e39ffff7df9ba\", \"base_unittests\": \"60fdffd73eaee26b93fe71f8ad26fb1a36e6c129\", \"base_util_unittests\": \"8981c9b59e8fef9eaed7541f41b7306a049408c7\", \"blink_common_unittests\": \"d042e601b56cc9135c9ac8961284daee30149c35\", \"blink_fuzzer_unittests\": \"c4e155a91f790efa994327f3db0344bf2b430fc5\", \"blink_heap_unittests\": \"963531c5c958ea012edb09997ad565d2b9bb8c55\", \"blink_platform_unittests\": \"2fea24d8b2dbe604db2ad2dcbf2f4c1b9cccfd7a\", \"blink_unittests\": \"3bd8a1e842087e2b4109945dd4562334fdec92cb\", \"boringssl_crypto_tests\": \"626ae57819736dd9ed44f80df2166a9f8b6c828b\", \"boringssl_ssl_tests\": \"94acb3823e1db16e26971befd905d2aecee545cf\", \"browser_tests\": \"0b660b58651488b699db0fcbdbfcc22e2d75d725\", \"capture_unittests\": \"81e8b46b37e10a382adb74779fab353305f990e7\", \"cast_unittests\": \"77d1f9271f8b613826a365b8a719195381303962\", \"cc_unittests\": \"e01980cdcf996e5e23edddd50cbe6d0c1a57ca1a\", \"chrome_app_unittests\": \"59596d1f2b75b1efb5018a960491ead22bae4b17\", \"chromedriver_unittests\": \"ba19a188c5aae263d98b4c1907eb07c7eae50ad9\", \"chromeos_components_unittests\": \"f4c59788b5e4d30d4703f78aa424e18ee6f3475d\", \"chromeos_unittests\": \"c2c3be7139634123cf31ed284f2b827862bda415\", \"components_browsertests\": \"731491692776f1c49c8d2814b3908cf981283394\", \"components_unittests\": \"0a69235e40b75e6c5730f1cea34c771a5340f304\", \"compositor_unittests\": \"c5bfb5165796ae5d1c3f24d08840fad14cb2d305\", \"content_browsertests\": \"c68b9416db1fa091064634d65316ca05d550e269\", \"content_unittests\": \"257b3e862bc6b2ea245efe55777beadd65ed6ddf\", \"crashpad_tests\": \"6f2d02fa7b14836832c184114e2de3c9eb296da4\", \"crypto_unittests\": \"ede510b5404da883201c1a6d021dca4724ba68ea\", \"dbus_unittests\": \"05861f7061a373f74325b3f4314d682f21c565c1\", \"device_unittests\": \"0f35ffbd1392069ca7b5b1b79406c9b5777b87b6\", \"display_unittests\": \"ee0fb48a79d7c04c36ea7de2d56050f3e58201ac\", \"events_unittests\": \"d0eb56994b9e634462ee7938bc4f429e8fd4b091\", \"exo_unittests\": \"440596758d6797c76ee9cd38b3cfa80305233a29\", \"extensions_browsertests\": \"62bbf0532847b29cbeadb4600bdca335fb8662e2\", \"extensions_unittests\": \"442158974c8b388ec7f8c3d543aaadf89202c3fc\", \"filesystem_service_unittests\": \"f8b7368d436c4a7d745113dabcc773d2cfbafd0b\", \"gcm_unit_tests\": \"cc034a3d095587fda11c9180071cf70e11fb5774\", \"gfx_unittests\": \"58c233965cbd62cb15c50caac4c19f16d635304f\", \"gin_unittests\": \"2d17195ad88ebc4edaf1f3275643c675ca4edd34\", \"gl_unittests_ozone\": \"302b7133983555c1d3c2648754d22ea3e6d7ac32\", \"google_apis_unittests\": \"a20f45215998395553f330d68c512f91a5d650b8\", \"gpu_unittests\": \"1d6bc31f13d85f253aaf42731149d7b6af3a0613\", \"gwp_asan_unittests\": \"d9b3fed1f1e139903ad4bb1b2d41b370fcdf9e8e\", \"interactive_ui_tests\": \"6ec268f9a53630182bb21a9c6faf6a41f7993d0c\", \"ipc_tests\": \"e20d99ba37825befc76272c8b949a7b2fb179cce\", \"jingle_unittests\": \"8f1852080fe64f4c8b196dcd39f5fafd5e507d95\", \"keyboard_unittests\": \"dd4443a769ea2c8729f9da2489ee851bdc88b8e8\", \"latency_unittests\": \"da2aab9a36eb3761ff09c600142a6768f9bb5187\", \"libjingle_xmpp_unittests\": \"a118ddf9ad7c3bee179ee4f4c9dcefeee3992efa\", \"liburlpattern_unittests\": \"019c3273243e7508c5bd39530e22e17e4247fd72\", \"media_blink_unittests\": \"949600b5432de240cd3964630c6fabdbbdc304a4\", \"media_unittests\": \"ece94e840acd498ca4d40d40d8efce69d9eaacc9\", \"message_center_unittests\": \"1f52761fb4968a4304052596845ea0506f782428\", \"midi_unittests\": \"a3dc980c54523663e4b996fa6b8e4b3915768068\", \"mojo_core_unittests\": \"c8c4ebcdd659ca646342ddea994b1e90091f2ef0\", \"mojo_unittests\": \"7c4431cd7ed24fefae95c5cfba76dfffd05ac7b6\", \"nacl_helper_nonsfi_unittests\": \"ec4a1c94254ee75c10fe781819cae3338d372dfc\", \"nacl_loader_unittests\": \"ecae586c63678974cc1cd0fa0b24442b6b03613d\", \"native_theme_unittests\": \"5f3fb6396bc7531e1f700ceaf932ab50fb4fc47d\", \"net_unittests\": \"ab2b165730dc66a97b785d9f8a40d3715c59422f\", \"ozone_gl_unittests\": \"a7504272a9eb5b6501bb0d21f19ffb8df559ba0f\", \"ozone_unittests\": \"a8eafdb6e3e8360fdbfdbcddbb0fc61b8ac939c6\", \"ozone_x11_unittests\": \"3d4e227fac9465f7c6ab96200935c1602eed4332\", \"pdf_unittests\": \"15560ada54fb704c0872bcc075a3ab00785f1f48\", \"perfetto_unittests\": \"e8e6b37aae5d49271461ad2840df174645cd8805\", \"ppapi_unittests\": \"617c2e12e9886a3b21cca74318d9c5cb366888b4\", \"printing_unittests\": \"4bf3760dc6fdd00773a4f8126b3ea47887c4fc7e\", \"remoting_unittests\": \"aad017101455774e116af1bf55b35cdf0d168057\", \"sandbox_linux_unittests\": \"6495547049482df1dc2228f4515c408b21d1afaa\", \"service_manager_unittests\": \"ed6ae15d0c352e05d63fa304231e7dd7f9ad3a9e\", \"services_unittests\": \"ad3481cb1d57d6b29d0e2fa218a2433b6da8d95d\", \"shell_dialogs_unittests\": \"0dfd629d444ed8da024e46952a8755aa5114f92b\", \"shell_encryption_unittests\": \"cf58706dfbec708ae093aba24d60673ed6416b04\", \"skia_unittests\": \"bce2b9ca1f11bb938f93d6050b5d8ce877c42e24\", \"snapshot_unittests\": \"32652ec5dc9f6db21249da143ed3a3ca57ae7c7e\", \"sql_unittests\": \"179fe047815936ab74609144154573190f38eaff\", \"storage_unittests\": \"3471c106c5553d571843cc233c36752184b29a72\", \"sync_integration_tests\": \"938f9d8dea8b52c8fe9ad7a97347bb80a4fec709\", \"traffic_annotation_auditor_unittests\": \"a8fd1df225a8af4da74b621760d6c70b0d3a7720\", \"ui_base_unittests\": \"2422afb339a77e5b0ab363be93964ab2ccc32923\", \"ui_chromeos_unittests\": \"fba7573ef58f1da8bd37be7be357063ec44ca8c7\", \"ui_touch_selection_unittests\": \"7253ce88f3cc847d542a5ecaa94f701f2ab1b7b4\", \"unit_tests\": \"c6c5fb48d5ef969ca74b669c7110f3c148df7b77\", \"url_unittests\": \"70895e4e581a3adc12e11c5d2ecc418d95e69270\", \"usage_time_limit_unittests\": \"85b1e23d421844da75720745591ef694c76dfd01\", \"views_unittests\": \"99c39ed0feee20d6e050f83eba86b23bc15a10e1\", \"viz_unittests\": \"a67d7e5f733a290da2a3aaa6b7c720b8aa8f533c\", \"wayland_client_perftests\": \"8e5477711c20f9c40a21a355a7d9184e32e811c2\", \"wm_unittests\": \"27501815e5df8761706423ab35d163124d6e925a\", \"wtf_unittests\": \"61e675934c555928cc1a4a20f1a03c4361af4ae9\", \"zlib_unittests\": \"34dbe2318a0cea145d78d7254f6460b5160f504d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_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-61-vlgi\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- EulaTest.AdditionalToS\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611979978530023",
"status": "COMPLETED",
"status_changed_ts": "1611979979074549",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45017",
"builder:linux-chromeos-rel",
"buildset:commit/git/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c34a12d7dec4008142b2a252b13339ed2fa38f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022141303766154352",
"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/8856711241282309072/+/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:516a40d3f4724710",
"user_agent:luci-scheduler"
],
"updated_ts": "1611985575956863",
"url": "https://ci.chromium.org/b/8856711241282309072",
"utcnow_ts": "1611985576357414"
}
}
[05:46:16.367] Invocation finished in 1h49m54.549568473s with status FAILED