[12:34:08.145] New invocation is queued and will start shortly
[12:34:09.335] Starting the invocation (attempt 1)
[12:34:09.394] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:34:09.397] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:34:09.397] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021293355679731840",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"435674e8121a2fb9db625b650dc69870029d3315\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021293355679731840",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/435674e8121a2fb9db625b650dc69870029d3315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/435674e8121a2fb9db625b650dc69870029d3315",
"gitiles_ref:refs/heads/master"
]
}
[12:34:10.375] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612787649516320",
"id": "8855863293579181536",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"435674e8121a2fb9db625b650dc69870029d3315\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612787650085936",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45239",
"builder:linux-chromeos-rel",
"buildset:commit/git/435674e8121a2fb9db625b650dc69870029d3315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/435674e8121a2fb9db625b650dc69870029d3315",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021293355679731840",
"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/8855863293579181536/+/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": "1612787650086175",
"url": "https://ci.chromium.org/b/8855863293579181536",
"utcnow_ts": "1612787650347568"
}
}
[12:34:10.375] Task URL: https://ci.chromium.org/b/8855863293579181536
[12:34:10.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:2:0) after 1m0s
[12:34:13.030] Received PubSub notification, asking Buildbucket for the build status
[12:34:15.433] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:10.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:2:0)
[12:35:10.401] Timer tick, asking Buildbucket for the build status
[12:35:10.648] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:10.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:4:0) after 1m0s
[12:36:10.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:4:0)
[12:36:10.669] Timer tick, asking Buildbucket for the build status
[12:36:10.724] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:10.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:5:0) after 1m0s
[12:37:10.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:5:0)
[12:37:10.764] Timer tick, asking Buildbucket for the build status
[12:37:10.886] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:10.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:6:0) after 1m0s
[12:38:10.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:6:0)
[12:38:10.953] Timer tick, asking Buildbucket for the build status
[12:38:11.099] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:11.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:7:0) after 1m0s
[12:39:11.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:7:0)
[12:39:11.118] Timer tick, asking Buildbucket for the build status
[12:39:11.538] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:11.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:8:0) after 1m0s
[12:40:11.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:8:0)
[12:40:11.628] Timer tick, asking Buildbucket for the build status
[12:40:11.771] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:11.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:9:0) after 1m0s
[12:41:11.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:9:0)
[12:41:11.819] Timer tick, asking Buildbucket for the build status
[12:41:11.936] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:11.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:10:0) after 1m0s
[12:42:11.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:10:0)
[12:42:11.986] Timer tick, asking Buildbucket for the build status
[12:42:12.224] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:12.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:11:0) after 1m0s
[12:43:12.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:11:0)
[12:43:12.257] Timer tick, asking Buildbucket for the build status
[12:43:12.586] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:12.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:12:0) after 1m0s
[12:44:12.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:12:0)
[12:44:12.623] Timer tick, asking Buildbucket for the build status
[12:44:12.756] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:12.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:13:0) after 1m0s
[12:45:12.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:13:0)
[12:45:12.908] Timer tick, asking Buildbucket for the build status
[12:45:13.122] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:13.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:14:0) after 1m0s
[12:46:13.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:14:0)
[12:46:13.477] Timer tick, asking Buildbucket for the build status
[12:46:13.597] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:13.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:15:0) after 1m0s
[12:47:13.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:15:0)
[12:47:13.682] Timer tick, asking Buildbucket for the build status
[12:47:13.756] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:13.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:16:0) after 1m0s
[12:48:13.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:16:0)
[12:48:13.801] Timer tick, asking Buildbucket for the build status
[12:48:13.869] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:13.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:17:0) after 1m0s
[12:49:13.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:17:0)
[12:49:13.948] Timer tick, asking Buildbucket for the build status
[12:49:14.409] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:14.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:18:0) after 1m0s
[12:50:14.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:18:0)
[12:50:14.558] Timer tick, asking Buildbucket for the build status
[12:50:14.872] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:14.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:19:0) after 1m0s
[12:51:14.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:19:0)
[12:51:14.891] Timer tick, asking Buildbucket for the build status
[12:51:14.949] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:14.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:20:0) after 1m0s
[12:52:15.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:20:0)
[12:52:15.131] Timer tick, asking Buildbucket for the build status
[12:52:15.374] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:15.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:21:0) after 1m0s
[12:53:15.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:21:0)
[12:53:15.474] Timer tick, asking Buildbucket for the build status
[12:53:15.598] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:15.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:22:0) after 1m0s
[12:54:15.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:22:0)
[12:54:15.847] Timer tick, asking Buildbucket for the build status
[12:54:16.338] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:16.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:23:0) after 1m0s
[12:55:16.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:23:0)
[12:55:16.740] Timer tick, asking Buildbucket for the build status
[12:55:16.916] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:16.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:24:0) after 1m0s
[12:56:17.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:24:0)
[12:56:17.130] Timer tick, asking Buildbucket for the build status
[12:56:17.226] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:17.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:25:0) after 1m0s
[12:57:17.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:25:0)
[12:57:17.305] Timer tick, asking Buildbucket for the build status
[12:57:17.376] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:17.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:26:0) after 1m0s
[12:58:17.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:26:0)
[12:58:17.443] Timer tick, asking Buildbucket for the build status
[12:58:17.705] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:17.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:27:0) after 1m0s
[12:59:17.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:27:0)
[12:59:17.686] Timer tick, asking Buildbucket for the build status
[12:59:17.803] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:17.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:28:0) after 1m0s
[13:00:17.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:28:0)
[13:00:17.841] Timer tick, asking Buildbucket for the build status
[13:00:17.904] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:17.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:29:0) after 1m0s
[13:01:17.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:29:0)
[13:01:17.947] Timer tick, asking Buildbucket for the build status
[13:01:18.006] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:18.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:30:0) after 1m0s
[13:02:18.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:30:0)
[13:02:18.051] Timer tick, asking Buildbucket for the build status
[13:02:18.133] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:18.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:31:0) after 1m0s
[13:03:18.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:31:0)
[13:03:18.185] Timer tick, asking Buildbucket for the build status
[13:03:18.692] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:18.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:32:0) after 1m0s
[13:04:18.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:32:0)
[13:04:18.679] Timer tick, asking Buildbucket for the build status
[13:04:18.819] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:18.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:33:0) after 1m0s
[13:05:18.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:33:0)
[13:05:18.870] Timer tick, asking Buildbucket for the build status
[13:05:19.133] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:19.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:34:0) after 1m0s
[13:06:19.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:34:0)
[13:06:19.199] Timer tick, asking Buildbucket for the build status
[13:06:19.442] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:19.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:35:0) after 1m0s
[13:07:19.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:35:0)
[13:07:19.450] Timer tick, asking Buildbucket for the build status
[13:07:19.721] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:19.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:36:0) after 1m0s
[13:08:19.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:36:0)
[13:08:19.733] Timer tick, asking Buildbucket for the build status
[13:08:19.793] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:19.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:37:0) after 1m0s
[13:09:19.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:37:0)
[13:09:19.767] Timer tick, asking Buildbucket for the build status
[13:09:19.879] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:19.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:38:0) after 1m0s
[13:10:19.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:38:0)
[13:10:19.908] Timer tick, asking Buildbucket for the build status
[13:10:20.197] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:20.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:39:0) after 1m0s
[13:11:20.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:39:0)
[13:11:20.338] Timer tick, asking Buildbucket for the build status
[13:11:20.395] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:20.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:40:0) after 1m0s
[13:12:20.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:40:0)
[13:12:20.447] Timer tick, asking Buildbucket for the build status
[13:12:20.661] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:20.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:41:0) after 1m0s
[13:13:20.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:41:0)
[13:13:20.720] Timer tick, asking Buildbucket for the build status
[13:13:20.790] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:20.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:42:0) after 1m0s
[13:14:20.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:42:0)
[13:14:20.844] Timer tick, asking Buildbucket for the build status
[13:14:21.290] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:21.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:43:0) after 1m0s
[13:15:21.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:43:0)
[13:15:21.393] Timer tick, asking Buildbucket for the build status
[13:15:21.663] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:21.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:44:0) after 1m0s
[13:16:21.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:44:0)
[13:16:21.734] Timer tick, asking Buildbucket for the build status
[13:16:21.798] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:45:0) after 1m0s
[13:17:21.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:45:0)
[13:17:21.817] Timer tick, asking Buildbucket for the build status
[13:17:21.987] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:21.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:46:0) after 1m0s
[13:18:21.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:46:0)
[13:18:21.999] Timer tick, asking Buildbucket for the build status
[13:18:22.077] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:22.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:47:0) after 1m0s
[13:19:22.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:47:0)
[13:19:22.199] Timer tick, asking Buildbucket for the build status
[13:19:22.300] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:22.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:48:0) after 1m0s
[13:20:22.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:48:0)
[13:20:22.360] Timer tick, asking Buildbucket for the build status
[13:20:22.613] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:22.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:49:0) after 1m0s
[13:21:22.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:49:0)
[13:21:22.621] Timer tick, asking Buildbucket for the build status
[13:21:22.760] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:22.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:50:0) after 1m0s
[13:22:22.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:50:0)
[13:22:22.808] Timer tick, asking Buildbucket for the build status
[13:22:23.138] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:23.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:51:0) after 1m0s
[13:23:23.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:51:0)
[13:23:23.173] Timer tick, asking Buildbucket for the build status
[13:23:23.267] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:23.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:52:0) after 1m0s
[13:24:23.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:52:0)
[13:24:23.323] Timer tick, asking Buildbucket for the build status
[13:24:23.520] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:23.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:53:0) after 1m0s
[13:25:23.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:53:0)
[13:25:23.685] Timer tick, asking Buildbucket for the build status
[13:25:23.853] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:23.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:54:0) after 1m0s
[13:26:23.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:54:0)
[13:26:23.989] Timer tick, asking Buildbucket for the build status
[13:26:24.095] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:24.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:55:0) after 1m0s
[13:27:24.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:55:0)
[13:27:24.153] Timer tick, asking Buildbucket for the build status
[13:27:24.734] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:24.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:56:0) after 1m0s
[13:28:24.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:56:0)
[13:28:24.903] Timer tick, asking Buildbucket for the build status
[13:28:25.226] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:25.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:57:0) after 1m0s
[13:29:25.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:57:0)
[13:29:25.305] Timer tick, asking Buildbucket for the build status
[13:29:25.823] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:25.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:58:0) after 1m0s
[13:30:26.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:58:0)
[13:30:26.214] Timer tick, asking Buildbucket for the build status
[13:30:26.396] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:59:0) after 1m0s
[13:31:26.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:59:0)
[13:31:26.501] Timer tick, asking Buildbucket for the build status
[13:31:27.260] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:27.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:60:0) after 1m0s
[13:32:27.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:60:0)
[13:32:27.329] Timer tick, asking Buildbucket for the build status
[13:32:27.476] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:27.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:61:0) after 1m0s
[13:33:27.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:61:0)
[13:33:27.610] Timer tick, asking Buildbucket for the build status
[13:33:28.086] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:28.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:62:0) after 1m0s
[13:34:28.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:62:0)
[13:34:28.206] Timer tick, asking Buildbucket for the build status
[13:34:28.336] Build 8855863293579181536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:28.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021293355679731840:63:0) after 1m0s
[13:34:40.448] Received PubSub notification, asking Buildbucket for the build status
[13:34:40.963] Build 8855863293579181536: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:34:40.963] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612791279602814",
"created_by": "project:chromium",
"created_ts": "1612787649516320",
"failure_reason": "BUILD_FAILURE",
"id": "8855863293579181536",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"435674e8121a2fb9db625b650dc69870029d3315\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- HintsFetcherBrowserTest.HintsFetcherNetworkOffline\"}, \"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\": \"435674e8121a2fb9db625b650dc69870029d3315\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-94-1j8q\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45239, \"got_angle_revision\": \"5712107a35b053c2e4ffb3a5690e6e482a0c84f5\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"435674e8121a2fb9db625b650dc69870029d3315\", \"got_revision_cp\": \"refs/heads/master@{#851675}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"0b911e791ef3aefc64d94c7c36b35f010acd7dc0\", \"got_v8_revision_cp\": \"refs/heads/9.0.139@{#1}\", \"got_webrtc_revision\": \"69c0118c5139a1a6b431d2f3f812dc9ed6af6b74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33186}\", \"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\": \"435674e8121a2fb9db625b650dc69870029d3315\", \"swarm_hashes_refs/heads/master(at){#851675}_without_patch\": {\"absl_hardening_tests\": \"0a1275ca387a1871f814e9e3bf3b1ce01e3d3bb6\", \"accessibility_unittests\": \"63ed8bd070a517285f8a35e01765492242646cc6\", \"angle_unittests\": \"d94138324516e074896520a421f0944e320c5d0a\", \"app_list_unittests\": \"138cd977870290243852ed0e05d9c14680dab150\", \"app_shell_unittests\": \"d7db8fc77db1773df867e201f808e9a687ecf003\", \"ash_unittests\": \"7d5ff549ef1ee27460000d71ead28c78e01abfa3\", \"aura_unittests\": \"0a91fc8bac385730a98e4bc081b22efb11cc9f09\", \"base_unittests\": \"541dadc7fbcabb7437b64997d8ea08ac1ec4117c\", \"base_util_unittests\": \"b532506f4ed1be57e92234dd0f72c87d45a98bd2\", \"blink_common_unittests\": \"63087f552431a95b25f1d87187dea16df0857a80\", \"blink_fuzzer_unittests\": \"73aa902739f5cf54381fee6ae0acf67275f31c39\", \"blink_heap_unittests\": \"394d30553f3ea6c2d3f16ad42bdfbe2abff7488c\", \"blink_platform_unittests\": \"6e27f8912a5992d28ae2270eeda8761ab1c5fa2c\", \"blink_unittests\": \"7bd7171a7734f4528bbddb3ddb71d1172f22e240\", \"boringssl_crypto_tests\": \"cb1be731c226a6d2ed70db075f5d0aa7251bc045\", \"boringssl_ssl_tests\": \"c9145bcb0aac768232680c736dafa2caf29b7f54\", \"browser_tests\": \"77fded119256652c7c78df2563de65fd7bf7ad8a\", \"capture_unittests\": \"885cbba5902408b594cc2ca45ada1480fa1ce5b7\", \"cast_unittests\": \"a5a47d03bdc790fa8200d8b5a4bd2829049dd696\", \"cc_unittests\": \"e9e9aaadac48fcd8919fe6191f24998152b1fb8e\", \"chrome_app_unittests\": \"696ff993336a9ff70c1584b58d7736f99abc907f\", \"chromedriver_unittests\": \"f46388918a84ca380dd7ea440c93ce8c1bdcee44\", \"chromeos_components_unittests\": \"b6aa5f28a4423f5076eb42a264e7d326189eb732\", \"chromeos_unittests\": \"4601d2e501aa0125e88e525ff6b60053ec7a4a99\", \"components_browsertests\": \"add439c50af98aa80ad95ea720c9124b91576177\", \"components_unittests\": \"3f10741db645b14b8f84dc7d44f2090791df044c\", \"compositor_unittests\": \"a82e42e753e800ccef9f8ff1eadfda5db9238689\", \"content_browsertests\": \"036e1f3be2b7b537f4fc8a69f5425a618000193a\", \"content_unittests\": \"b8e3ced3336f9dc7f60d6063feb5e1e5a1947736\", \"crashpad_tests\": \"7daf4d1c96d02b8a2ab0ae590aa0ebb64b64f6c9\", \"crypto_unittests\": \"a6e13c19b1d0971be7ba10d7b7610bce8cb64895\", \"dbus_unittests\": \"586c9f5edb22dc5c7edbc09f48822af8410f3268\", \"device_unittests\": \"4c3034a5f7b3c6a462be68ae860a32325062a258\", \"display_unittests\": \"00bedaca0b1b62c45238f25345387497c1994a98\", \"events_unittests\": \"950ce1b4111e168d19e43c303c0afdd72dcf4f4e\", \"exo_unittests\": \"bed42d7366d014b3d034ef2d5c1ee92d2d53465f\", \"extensions_browsertests\": \"ad491ec8f521f47d7efa7e4be813065304af80e9\", \"extensions_unittests\": \"424158ea1d3a75f4d25f73be5ac5c6265861fc2a\", \"filesystem_service_unittests\": \"ca273ea5bbb6d59f44d660740e89fa2dc14ebe0f\", \"gcm_unit_tests\": \"bed59159db538d2793ed8b8fd9499db8f21cfc15\", \"gfx_unittests\": \"c5eba0392484aa24b089a0c1f4bb8c410290c737\", \"gin_unittests\": \"96ba11aaa73e5825758ebd2b88c38f3d3a0cedec\", \"gl_unittests_ozone\": \"23200abefe246019c59438d38f9f71d9678d96b4\", \"google_apis_unittests\": \"8cda16a004e26eb60edb02f94a7996870691cf81\", \"gpu_unittests\": \"79af5347a25b6fbe23475e80a2b731f642a009be\", \"gwp_asan_unittests\": \"db92ffcf727bcb790ceaef603426ce086095a482\", \"interactive_ui_tests\": \"c92ad1e188a35723f352fb4cb454f27639c56b78\", \"ipc_tests\": \"b5b308daeee791992d1dfa2fe26c0c28da45fcbe\", \"jingle_unittests\": \"0ebb0b8df44e1c8302ed28508e41cfe3c39b840c\", \"keyboard_unittests\": \"1183010555bf4d36d39b2c7a8c458a75aa48e89f\", \"latency_unittests\": \"4ac6e6a222de8af8dfa4b0a668cf44af0c948639\", \"libjingle_xmpp_unittests\": \"3c5b81617baf43e661dbdefb9a493a8aac326b42\", \"liburlpattern_unittests\": \"f006f2b1da2f7ea3bff6aa391536fd30902b55db\", \"media_blink_unittests\": \"ea50f9966f0735c402c254deefc0e66db7173fa1\", \"media_unittests\": \"ad47c2f147473e0e9cf83c6a5046ecfa99c21956\", \"message_center_unittests\": \"35180cff51011a2370c30ab72c644de252551ee2\", \"midi_unittests\": \"bded905099ec4f8fc020f4fcfca655b7eea2e3eb\", \"mojo_core_unittests\": \"535a53e1652ff2bf1cbe25160dfa1cc9e874e7ff\", \"mojo_unittests\": \"6204085e2b8432bbf031f332ab66ce473a8ba74d\", \"nacl_helper_nonsfi_unittests\": \"72b51ca1fa805da0ccec0c0d758b4f75aadb789b\", \"nacl_loader_unittests\": \"7c6285a5c1d85ec407c08981b1fe777e3e29270c\", \"native_theme_unittests\": \"9166a898913c5da551de865f981736941b04de6a\", \"net_unittests\": \"57641dd09e1d21a507ba8adbf7bf9e3eedaa31e4\", \"ozone_gl_unittests\": \"2e9da67a5fc9b187523d32c0933ba176fd5cb396\", \"ozone_unittests\": \"08d5338ab630e65b9e67062eddd67db851182789\", \"ozone_x11_unittests\": \"4e317798dc6c385a7f342d0a00e485a2227172c8\", \"pdf_unittests\": \"bb27048812b859d341fc835b209764a5c26a14cc\", \"perfetto_unittests\": \"72e26f4347e2e9135f057d90057e396f66dd1bb7\", \"ppapi_unittests\": \"f87fc74a0f91d29acc1561678e023be3e17d2b4d\", \"printing_unittests\": \"2c86b1a350eabe79c20c41f3d91b58495ca55600\", \"remoting_unittests\": \"4de4e5c2da77b393685e0ad6528e48460e84b605\", \"sandbox_linux_unittests\": \"0e875bf8a519062382720a242e7375a0cacbd705\", \"service_manager_unittests\": \"27a34e062e437233ff3b8fe81685703e7aaeac2f\", \"services_unittests\": \"52c20bfb552e235e9fcaa74494f22faec3110f93\", \"shell_dialogs_unittests\": \"7060b24b43bde44140d9ffd650ca31cb226c1794\", \"shell_encryption_unittests\": \"cc0cbf47ba867c9acae6af45309063d569386408\", \"skia_unittests\": \"43d33efcd0878ffa6b24cfe241a29a69327c9b7a\", \"snapshot_unittests\": \"8a7c02d3776c10c70f916d03951bda055de56ed3\", \"sql_unittests\": \"f220d5e61fbb710848561259dcca65f4b11b38fa\", \"storage_unittests\": \"4e99db3403177deb27ae19d755273ba68ea871e1\", \"sync_integration_tests\": \"0f69fe83f1c3d0a625834d32910fa9914c43b4b8\", \"traffic_annotation_auditor_unittests\": \"23236310e6231399cacbfcfefb6ee2cfe7474338\", \"ui_base_unittests\": \"62c277dbf408326a67b73351881df58868a40d98\", \"ui_chromeos_unittests\": \"1ff52e8c80c77a936d0f3916b08ad12e9dc1a5a0\", \"ui_touch_selection_unittests\": \"cb1488078e6f2ff2cc0ddcefbf667bdd8958505f\", \"unit_tests\": \"54fd4b2dba0702bd3c4989d146f6080d85a89b0d\", \"url_unittests\": \"e048e3b1d872e7d7b1e934a675c4563846094429\", \"usage_time_limit_unittests\": \"9c3d231cb7ddee6858e4d5cab443d92d246f4783\", \"views_unittests\": \"81b2bb77dc45eb0793f4ce1c25926e66de768a76\", \"viz_unittests\": \"350ae2b2ede2e81c03a7677ddb747bd7d79fabd7\", \"wayland_client_perftests\": \"a7a4fbad98a7c1834cb07be75bc0288888557a52\", \"wm_unittests\": \"6121fac14823787c1c2ca4356b7603840d0d9e9f\", \"wtf_unittests\": \"5b20952b4f7c65ce6ace329f946133ff579cb123\", \"zlib_unittests\": \"4c24c206f9972da769e308ee74b2beca2ac68882\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-94-1j8q\"], \"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\": [\"5681-01e7fd1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- HintsFetcherBrowserTest.HintsFetcherNetworkOffline\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612787652546054",
"status": "COMPLETED",
"status_changed_ts": "1612787652857767",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45239",
"builder:linux-chromeos-rel",
"buildset:commit/git/435674e8121a2fb9db625b650dc69870029d3315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/435674e8121a2fb9db625b650dc69870029d3315",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021293355679731840",
"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/8855863293579181536/+/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:519a7417ed9b2f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612791280157613",
"url": "https://ci.chromium.org/b/8855863293579181536",
"utcnow_ts": "1612791280903068"
}
}
[13:34:40.963] Invocation finished in 1h0m32.833306982s with status FAILED