[21:58:46.653] New invocation is queued and will start shortly
[21:58:48.303] Starting the invocation (attempt 1)
[21:58:48.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:58:48.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:58:48.354] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021892010256088704",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"656a00c946e3ba7209afab0e701dbaee013f915e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021892010256088704",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/656a00c946e3ba7209afab0e701dbaee013f915e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/656a00c946e3ba7209afab0e701dbaee013f915e",
"gitiles_ref:refs/heads/master"
]
}
[21:58:50.423] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612216728536797",
"id": "8856461947616715200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"656a00c946e3ba7209afab0e701dbaee013f915e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612216729881186",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45066",
"builder:linux-chromeos-rel",
"buildset:commit/git/656a00c946e3ba7209afab0e701dbaee013f915e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/656a00c946e3ba7209afab0e701dbaee013f915e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021892010256088704",
"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/8856461947616715200/+/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": "1612216729881444",
"url": "https://ci.chromium.org/b/8856461947616715200",
"utcnow_ts": "1612216730403795"
}
}
[21:58:50.423] Task URL: https://ci.chromium.org/b/8856461947616715200
[21:58:50.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:2:0) after 1m0s
[21:59:29.880] Received PubSub notification, asking Buildbucket for the build status
[21:59:30.026] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:50.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:2:0)
[21:59:50.717] Timer tick, asking Buildbucket for the build status
[21:59:50.848] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:50.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:4:0) after 1m0s
[22:00:51.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:4:0)
[22:00:51.058] Timer tick, asking Buildbucket for the build status
[22:00:51.269] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:51.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:5:0) after 1m0s
[22:01:51.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:5:0)
[22:01:51.483] Timer tick, asking Buildbucket for the build status
[22:01:51.696] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:51.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:6:0) after 1m0s
[22:02:51.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:6:0)
[22:02:51.829] Timer tick, asking Buildbucket for the build status
[22:02:51.962] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:51.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:7:0) after 1m0s
[22:03:52.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:7:0)
[22:03:52.205] Timer tick, asking Buildbucket for the build status
[22:03:52.351] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:52.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:8:0) after 1m0s
[22:04:52.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:8:0)
[22:04:52.390] Timer tick, asking Buildbucket for the build status
[22:04:52.506] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:9:0) after 1m0s
[22:05:52.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:9:0)
[22:05:52.538] Timer tick, asking Buildbucket for the build status
[22:05:52.608] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:52.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:10:0) after 1m0s
[22:06:52.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:10:0)
[22:06:52.658] Timer tick, asking Buildbucket for the build status
[22:06:52.742] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:52.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:11:0) after 1m0s
[22:07:52.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:11:0)
[22:07:52.766] Timer tick, asking Buildbucket for the build status
[22:07:53.326] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:53.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:12:0) after 1m0s
[22:08:53.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:12:0)
[22:08:53.354] Timer tick, asking Buildbucket for the build status
[22:08:53.693] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:53.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:13:0) after 1m0s
[22:09:53.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:13:0)
[22:09:53.740] Timer tick, asking Buildbucket for the build status
[22:09:53.801] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:53.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:14:0) after 1m0s
[22:10:53.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:14:0)
[22:10:53.859] Timer tick, asking Buildbucket for the build status
[22:10:53.925] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:53.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:15:0) after 1m0s
[22:11:53.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:15:0)
[22:11:53.982] Timer tick, asking Buildbucket for the build status
[22:11:54.069] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:54.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:16:0) after 1m0s
[22:12:54.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:16:0)
[22:12:54.134] Timer tick, asking Buildbucket for the build status
[22:12:54.486] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:54.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:17:0) after 1m0s
[22:13:54.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:17:0)
[22:13:54.558] Timer tick, asking Buildbucket for the build status
[22:13:54.663] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:54.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:18:0) after 1m0s
[22:14:54.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:18:0)
[22:14:54.711] Timer tick, asking Buildbucket for the build status
[22:14:54.959] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:54.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:19:0) after 1m0s
[22:15:54.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:19:0)
[22:15:54.971] Timer tick, asking Buildbucket for the build status
[22:15:55.037] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:55.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:20:0) after 1m0s
[22:16:55.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:20:0)
[22:16:55.067] Timer tick, asking Buildbucket for the build status
[22:16:55.313] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:55.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:21:0) after 1m0s
[22:17:55.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:21:0)
[22:17:55.336] Timer tick, asking Buildbucket for the build status
[22:17:55.409] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:55.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:22:0) after 1m0s
[22:18:55.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:22:0)
[22:18:55.456] Timer tick, asking Buildbucket for the build status
[22:18:55.559] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:55.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:23:0) after 1m0s
[22:19:55.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:23:0)
[22:19:55.618] Timer tick, asking Buildbucket for the build status
[22:19:55.691] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:55.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:24:0) after 1m0s
[22:20:55.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:24:0)
[22:20:55.692] Timer tick, asking Buildbucket for the build status
[22:20:55.767] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:55.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:25:0) after 1m0s
[22:21:55.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:25:0)
[22:21:55.842] Timer tick, asking Buildbucket for the build status
[22:21:56.057] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:56.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:26:0) after 1m0s
[22:22:56.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:26:0)
[22:22:56.194] Timer tick, asking Buildbucket for the build status
[22:22:56.348] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:56.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:27:0) after 1m0s
[22:23:56.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:27:0)
[22:23:56.463] Timer tick, asking Buildbucket for the build status
[22:23:56.601] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:56.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:28:0) after 1m0s
[22:24:56.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:28:0)
[22:24:56.784] Timer tick, asking Buildbucket for the build status
[22:24:56.936] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:56.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:29:0) after 1m0s
[22:25:56.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:29:0)
[22:25:56.954] Timer tick, asking Buildbucket for the build status
[22:25:57.143] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:57.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:30:0) after 1m0s
[22:26:57.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:30:0)
[22:26:57.300] Timer tick, asking Buildbucket for the build status
[22:26:57.362] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:57.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:31:0) after 1m0s
[22:27:57.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:31:0)
[22:27:57.543] Timer tick, asking Buildbucket for the build status
[22:27:57.822] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:57.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:32:0) after 1m0s
[22:28:57.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:32:0)
[22:28:57.880] Timer tick, asking Buildbucket for the build status
[22:28:58.866] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:58.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:33:0) after 1m0s
[22:29:59.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:33:0)
[22:29:59.048] Timer tick, asking Buildbucket for the build status
[22:29:59.124] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:59.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:34:0) after 1m0s
[22:30:59.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:34:0)
[22:30:59.307] Timer tick, asking Buildbucket for the build status
[22:30:59.373] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:59.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:35:0) after 1m0s
[22:31:59.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:35:0)
[22:31:59.422] Timer tick, asking Buildbucket for the build status
[22:31:59.516] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:59.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:36:0) after 1m0s
[22:32:59.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:36:0)
[22:32:59.560] Timer tick, asking Buildbucket for the build status
[22:32:59.630] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:59.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:37:0) after 1m0s
[22:33:59.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:37:0)
[22:33:59.649] Timer tick, asking Buildbucket for the build status
[22:33:59.741] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:59.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:38:0) after 1m0s
[22:34:59.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:38:0)
[22:34:59.841] Timer tick, asking Buildbucket for the build status
[22:34:59.949] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:59.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:39:0) after 1m0s
[22:35:59.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:39:0)
[22:35:59.954] Timer tick, asking Buildbucket for the build status
[22:36:00.109] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:40:0) after 1m0s
[22:37:00.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:40:0)
[22:37:00.132] Timer tick, asking Buildbucket for the build status
[22:37:00.302] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:00.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:41:0) after 1m0s
[22:38:00.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:41:0)
[22:38:00.321] Timer tick, asking Buildbucket for the build status
[22:38:00.633] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:00.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:42:0) after 1m0s
[22:39:00.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:42:0)
[22:39:00.875] Timer tick, asking Buildbucket for the build status
[22:39:01.211] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:43:0) after 1m0s
[22:40:01.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:43:0)
[22:40:01.653] Timer tick, asking Buildbucket for the build status
[22:40:01.972] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:01.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:44:0) after 1m0s
[22:41:02.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:44:0)
[22:41:02.466] Timer tick, asking Buildbucket for the build status
[22:41:02.733] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:02.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:45:0) after 1m0s
[22:42:02.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:45:0)
[22:42:02.756] Timer tick, asking Buildbucket for the build status
[22:42:03.361] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:03.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:46:0) after 1m0s
[22:43:03.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:46:0)
[22:43:03.359] Timer tick, asking Buildbucket for the build status
[22:43:03.616] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:03.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:47:0) after 1m0s
[22:44:03.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:47:0)
[22:44:03.653] Timer tick, asking Buildbucket for the build status
[22:44:03.833] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:03.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:48:0) after 1m0s
[22:45:03.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:48:0)
[22:45:03.963] Timer tick, asking Buildbucket for the build status
[22:45:04.095] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:04.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:49:0) after 1m0s
[22:46:04.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:49:0)
[22:46:04.199] Timer tick, asking Buildbucket for the build status
[22:46:04.418] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:04.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:50:0) after 1m0s
[22:47:04.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:50:0)
[22:47:04.457] Timer tick, asking Buildbucket for the build status
[22:47:04.624] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:51:0) after 1m0s
[22:48:04.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:51:0)
[22:48:04.732] Timer tick, asking Buildbucket for the build status
[22:48:05.199] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:05.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:52:0) after 1m0s
[22:49:05.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:52:0)
[22:49:05.353] Timer tick, asking Buildbucket for the build status
[22:49:05.831] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:05.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:53:0) after 1m0s
[22:50:06.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:53:0)
[22:50:06.073] Timer tick, asking Buildbucket for the build status
[22:50:06.218] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:06.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:54:0) after 1m0s
[22:51:06.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:54:0)
[22:51:06.238] Timer tick, asking Buildbucket for the build status
[22:51:06.318] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:55:0) after 1m0s
[22:52:06.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:55:0)
[22:52:06.334] Timer tick, asking Buildbucket for the build status
[22:52:06.480] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:06.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:56:0) after 1m0s
[22:53:06.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:56:0)
[22:53:06.521] Timer tick, asking Buildbucket for the build status
[22:53:06.936] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:06.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:57:0) after 1m0s
[22:54:07.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:57:0)
[22:54:07.026] Timer tick, asking Buildbucket for the build status
[22:54:07.440] Build 8856461947616715200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:07.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021892010256088704:58:0) after 1m0s
[22:54:16.105] Received PubSub notification, asking Buildbucket for the build status
[22:54:16.193] Build 8856461947616715200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:54:16.193] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612220055320721",
"created_by": "project:chromium",
"created_ts": "1612216728536797",
"id": "8856461947616715200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"656a00c946e3ba7209afab0e701dbaee013f915e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"656a00c946e3ba7209afab0e701dbaee013f915e\", \"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-43-n7n2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45066, \"got_angle_revision\": \"e3096d0729df059f1d251405d16eef9e71ba2516\", \"got_dawn_revision\": \"d7aacdda1119d419ce886f9ba4b9401de2834bed\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"656a00c946e3ba7209afab0e701dbaee013f915e\", \"got_revision_cp\": \"refs/heads/master@{#849274}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"4d22b2c30f55e7edf6c2340d165e85fd386bd489\", \"got_v8_revision\": \"5bc36e7aec3d7acdb0cb6bca02c0788d5993abbc\", \"got_v8_revision_cp\": \"refs/heads/9.0.106@{#1}\", \"got_webrtc_revision\": \"989e6e7d22e281dde977c349355a8f1d76a8dff3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33120}\", \"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\": \"656a00c946e3ba7209afab0e701dbaee013f915e\", \"swarm_hashes_refs/heads/master(at){#849274}_without_patch\": {\"absl_hardening_tests\": \"defd4b9858d6b5e9d9b19597949a04bf10d5dc58\", \"accessibility_unittests\": \"4753d3d1a6bb13528da03e4f045a9086edfe7f0f\", \"angle_unittests\": \"f27f2182cd79a553cce8558cf041eb61251354a9\", \"app_list_unittests\": \"5440b6dd8f261940aa789b4fbcdec284a1dc36ed\", \"app_shell_unittests\": \"7c509705babb6d2f568a7492fb71fc61f04f985b\", \"ash_unittests\": \"c2b3571a4daaf76698dd94c3fc4de1e17b7b62ac\", \"aura_unittests\": \"fe12c372743ebafb56271749e490d9cc5123fc6a\", \"base_unittests\": \"1e7de341840c8e251d29cb6b1b381d3731bda594\", \"base_util_unittests\": \"e6c083cc998abfcf3714cab89f36e1f466a98d40\", \"blink_common_unittests\": \"7156b8d4eecdfd7e7e06151b148644b201b794d2\", \"blink_fuzzer_unittests\": \"85aac2c9e49d96b90ebbe6b1603bd6684b6b5b6f\", \"blink_heap_unittests\": \"ccae91866dfe7aa6d41599a86bed3cf9b531e0b9\", \"blink_platform_unittests\": \"d39b8246a742d4e3ecf2fd20a6674bbabf1e88a6\", \"blink_unittests\": \"2db39b5b7fbad50389a9d635433402026c3f74f1\", \"boringssl_crypto_tests\": \"d0ac1aa4c2f56a63bda32b0bab37f0b6ed247451\", \"boringssl_ssl_tests\": \"fbbbb4ed4564242f880f16ead1629627e7f10fc4\", \"browser_tests\": \"5153ef3ea4faaef58bcdb72a52d10d4999958046\", \"capture_unittests\": \"610dc0d2529084171d542b6dd459799c422c067f\", \"cast_unittests\": \"6f64c46c7c1c69768fbc5c39c04fbfa69cf64cd3\", \"cc_unittests\": \"9fef0163ffbfda527de9f3348f5e4dc1724fbb01\", \"chrome_app_unittests\": \"72428950ae4d1466f9c7f130a751a2e8b0912da9\", \"chromedriver_unittests\": \"ed1afb5314b078abd6250a0da37af1f1170cd170\", \"chromeos_components_unittests\": \"d0b45bd69c8964fc0972c6c958d872c5198d0b3d\", \"chromeos_unittests\": \"97cfa8ea8c483bf15c6973bfde6e34f1672b4c3f\", \"components_browsertests\": \"d2be9806b24a95ab763696b2b78f7405dd6b90f9\", \"components_unittests\": \"50edb206daa7357a9056c2881bb0ed1ad956fe2d\", \"compositor_unittests\": \"f547f94e8324b5a348a4d5b3f1d087c7911d3ad2\", \"content_browsertests\": \"6453e7f1f5a7108a745bccaf91694840b7bdde23\", \"content_unittests\": \"f83392e41a1434f044512343b0f7a65d61e37dbc\", \"crashpad_tests\": \"30b7c6469082f4949d39975126712df152ac6c8a\", \"crypto_unittests\": \"23481651d4f5df0e441cb37c977e591efc56761d\", \"dbus_unittests\": \"6829bb6f101b91b4bcd0a19d96a2f4d5f0a57285\", \"device_unittests\": \"3cc34733848a9c7efb5cccb2dbe034fd99cb6d37\", \"display_unittests\": \"79c39fbe7af4f50d277ec4a2ff4b2298d62a45e7\", \"events_unittests\": \"64bd5daeb0905dd988feaa3d37e6679aec680bb2\", \"exo_unittests\": \"ed9816bf0b809a1176dbcb49d7fb35cef18ec1fc\", \"extensions_browsertests\": \"5ac638764551544985762f0c086c42f8f63a9c54\", \"extensions_unittests\": \"4ce5de25d247c5f7bf4ad0b3cd0943109a16b94d\", \"filesystem_service_unittests\": \"ca33493ed7e60d4ee0e0166298b3846a5adcc9cd\", \"gcm_unit_tests\": \"a81453dcb540600a7a24ac2d6c743609f19b8ebc\", \"gfx_unittests\": \"98235421d8c87b2caed45adc80219b9ee442ae68\", \"gin_unittests\": \"b92c844da250c6baa631061215ad645863a286f2\", \"gl_unittests_ozone\": \"ba211e8ab652b73f9dd5388b48a1b9d5f61451b0\", \"google_apis_unittests\": \"c8fcaa7491d0979a32cf8d97911601d84b0c682b\", \"gpu_unittests\": \"72a486808adcc127251d908fdd623e89bfc43ff8\", \"gwp_asan_unittests\": \"2025895c9c9effd76b24758ebf7c9cd367160cca\", \"interactive_ui_tests\": \"9a450ccf46fee2eb36ff11c0053fffa03f21f766\", \"ipc_tests\": \"38990ecf8f298ba4b1578811f5854fef70df4ca5\", \"jingle_unittests\": \"169927f5b8b5419cff4cfd1742753ca389d25796\", \"keyboard_unittests\": \"7aa69bc125317e0695fb7c7aff33bf5ca3bc86da\", \"latency_unittests\": \"61b85fb0f6eda477b2e0690a2fb93a3a41c5db46\", \"libjingle_xmpp_unittests\": \"308fb52da51274578ae1a75ccb17f289ae83e926\", \"liburlpattern_unittests\": \"978c3f4d3d4f2ff816db128beceb758f6b3842eb\", \"media_blink_unittests\": \"b99287f0d7a6e2adf878757816b37c8b30ebf558\", \"media_unittests\": \"3bde0a2b2ea95adc8c4919bf14f5cfe50b1318da\", \"message_center_unittests\": \"a343ecf5330406c1a756e89e987c6a87ef8795bb\", \"midi_unittests\": \"743bdaf5af2df85590732a047b495d30ad546c7f\", \"mojo_core_unittests\": \"af4e5432e52beb0bf5464b7876b482d89693bcef\", \"mojo_unittests\": \"231a88066cae3eaed6caf1ade13ab79721201946\", \"nacl_helper_nonsfi_unittests\": \"1c96ee6b5a548d75c1e4b0400a330d9fe5ff3574\", \"nacl_loader_unittests\": \"53d79a320a9dad4e285053bef079f4bc616fd19f\", \"native_theme_unittests\": \"6b0eff40f53a563de7c4410b755d2b2a57901ddf\", \"net_unittests\": \"68c2b4d5a7136548c793e4936bd76eae303f8735\", \"ozone_gl_unittests\": \"09994af0c0c33d0e91836aebb2fa1ddf82455afd\", \"ozone_unittests\": \"aa7cfbd283c19517bbf86185ab9d38bfbaba9961\", \"ozone_x11_unittests\": \"9a878a04535006780608fb4e0a1129f007656bb9\", \"pdf_unittests\": \"c1fbd54360b9d7ca23752792b1d8b0ecf2a7c1f4\", \"perfetto_unittests\": \"2332f14409b7e12785180b4b889a967a429c7a08\", \"ppapi_unittests\": \"85261c98f20bdd64b2add0fca7e4c8937b3d97e5\", \"printing_unittests\": \"296033ca270eda660cb51cb914a03103972e5fb7\", \"remoting_unittests\": \"148a841c2b6eeae7eb41ac8a25e5604008b53ca3\", \"sandbox_linux_unittests\": \"e30bae86d84fae7c8839be2c924a87aa58f0cb53\", \"service_manager_unittests\": \"828eb41c1b0834858bc0b4f7f3d9f33251fca7a8\", \"services_unittests\": \"7225d622f60c1c04f250e01e0f31ed3b5e64a469\", \"shell_dialogs_unittests\": \"b56a286ffa1653f513f4c6662e1a7ab64926330b\", \"shell_encryption_unittests\": \"c74cde6e7f021eaa0f877bb53f677a4ac5163a13\", \"skia_unittests\": \"8d5d0c2ca422fc99f52328b8357b873221d43be8\", \"snapshot_unittests\": \"306333a376ed5a4c2e914ce2540d8bc28206523c\", \"sql_unittests\": \"e1c8ccc5f2910efb15b29429cb9d3f7547277c1e\", \"storage_unittests\": \"b93860e308e8e3feb3141263ffd44c514572601f\", \"sync_integration_tests\": \"649c0fd7a7670dbb3e2aff7ad1baa1ae600e1be0\", \"traffic_annotation_auditor_unittests\": \"74e14e8cca6ecf968cbbf0e453eb6b81fb0a7c9c\", \"ui_base_unittests\": \"99362a2372deb4754f051e9e9a6b7e83891cdb59\", \"ui_chromeos_unittests\": \"fcbf510a1cd5fa0be2ffc971aeb6285ce9cdcde3\", \"ui_touch_selection_unittests\": \"0728d422f88776aea2a22b3ff5db08648bbef9a0\", \"unit_tests\": \"095ffdade7f4527f93883d03000b3371f6a0ed9a\", \"url_unittests\": \"511c98abb877def912bf60b5ff437d1874f68754\", \"usage_time_limit_unittests\": \"a950f2192cbe48e94773d579f2787d302a4f25c8\", \"views_unittests\": \"cb35e3311fa08d0eb25b53f02e027815fa633ab6\", \"viz_unittests\": \"0528b1f48c06b95ece1cc70d3bd90dfab3392f44\", \"wayland_client_perftests\": \"2c7c66cf726186d9c11c6814d0f0e8e0973c7bfe\", \"wm_unittests\": \"e17ba2b44bbdef6f26c94d5ab84175bf5278d475\", \"wtf_unittests\": \"4266a1ec2e71bcf8a0d7209cd9d67fddb51e5771\", \"zlib_unittests\": \"df65d6b834330b2600f8ac22d1d732ab107e6926\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4e62a2152674f2c3254d7105a557302458cce07b755aab1e21dcbd6c7ddeb832_v2\", \"builder_83d41522ef30b186d170824fe4af0f819b6d8e33bbc59ab4098fd9fd1af4e43b_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9f247f4686c5cf47ea762220cc037b3e853f43c1442c10d387faa806e919f9a9_v2\", \"builder_a16dce727623db190129e730193443d0ae3080534f372fd612ff56e110b9028d_v2\", \"builder_fe5305c96c631c9bcc74a68d877d31a94a8d3c28663b28d2fadfc8f798de4ec0_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-43-n7n2\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612216769251629",
"status": "COMPLETED",
"status_changed_ts": "1612220055634636",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45066",
"builder:linux-chromeos-rel",
"buildset:commit/git/656a00c946e3ba7209afab0e701dbaee013f915e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/656a00c946e3ba7209afab0e701dbaee013f915e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021892010256088704",
"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/8856461947616715200/+/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:51786c8ac0282a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612220055640811",
"url": "https://ci.chromium.org/b/8856461947616715200",
"utcnow_ts": "1612220056185306"
}
}
[22:54:16.193] Invocation finished in 55m29.557338634s with status SUCCEEDED