[21:58:52.199] New invocation is queued and will start shortly
[21:58:53.266] Starting the invocation (attempt 1)
[21:58:53.314] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:58:53.314] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:58:53.314] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023613346802086144",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023613346802086144",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"gitiles_ref:refs/heads/master"
]
}
[21:58:54.021] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610575133453184",
"id": "8858183284823092896",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610575133891316",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44577",
"builder:linux-chromeos-rel",
"buildset:commit/git/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023613346802086144",
"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/8858183284823092896/+/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": "1610575133891578",
"url": "https://ci.chromium.org/b/8858183284823092896",
"utcnow_ts": "1610575134013562"
}
}
[21:58:54.021] Task URL: https://ci.chromium.org/b/8858183284823092896
[21:58:54.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:2:0) after 1m0s
[21:59:23.184] Received PubSub notification, asking Buildbucket for the build status
[21:59:23.331] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:54.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:2:0)
[21:59:54.028] Timer tick, asking Buildbucket for the build status
[21:59:54.257] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:54.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:4:0) after 1m0s
[22:00:54.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:4:0)
[22:00:54.285] Timer tick, asking Buildbucket for the build status
[22:00:54.591] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:54.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:5:0) after 1m0s
[22:01:54.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:5:0)
[22:01:54.619] Timer tick, asking Buildbucket for the build status
[22:01:54.759] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:6:0) after 1m0s
[22:02:54.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:6:0)
[22:02:54.941] Timer tick, asking Buildbucket for the build status
[22:02:55.115] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:55.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:7:0) after 1m0s
[22:03:55.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:7:0)
[22:03:55.290] Timer tick, asking Buildbucket for the build status
[22:03:55.485] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:55.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:8:0) after 1m0s
[22:04:55.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:8:0)
[22:04:55.539] Timer tick, asking Buildbucket for the build status
[22:04:56.417] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:56.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:9:0) after 1m0s
[22:05:56.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:9:0)
[22:05:56.447] Timer tick, asking Buildbucket for the build status
[22:05:56.639] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:56.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:10:0) after 1m0s
[22:06:56.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:10:0)
[22:06:56.658] Timer tick, asking Buildbucket for the build status
[22:06:56.828] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:56.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:11:0) after 1m0s
[22:07:56.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:11:0)
[22:07:56.849] Timer tick, asking Buildbucket for the build status
[22:07:56.956] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:56.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:12:0) after 1m0s
[22:08:57.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:12:0)
[22:08:57.063] Timer tick, asking Buildbucket for the build status
[22:08:57.277] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:57.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:13:0) after 1m0s
[22:09:57.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:13:0)
[22:09:57.378] Timer tick, asking Buildbucket for the build status
[22:09:57.470] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:57.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:14:0) after 1m0s
[22:10:57.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:14:0)
[22:10:57.991] Timer tick, asking Buildbucket for the build status
[22:10:58.097] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:58.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:15:0) after 1m0s
[22:11:58.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:15:0)
[22:11:58.411] Timer tick, asking Buildbucket for the build status
[22:11:58.477] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:58.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:16:0) after 1m0s
[22:12:58.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:16:0)
[22:12:58.600] Timer tick, asking Buildbucket for the build status
[22:12:58.691] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:58.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:17:0) after 1m0s
[22:13:58.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:17:0)
[22:13:58.838] Timer tick, asking Buildbucket for the build status
[22:13:58.947] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:58.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:18:0) after 1m0s
[22:14:59.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:18:0)
[22:14:59.024] Timer tick, asking Buildbucket for the build status
[22:14:59.083] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:59.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:19:0) after 1m0s
[22:15:59.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:19:0)
[22:15:59.624] Timer tick, asking Buildbucket for the build status
[22:15:59.772] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:59.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:20:0) after 1m0s
[22:17:00.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:20:0)
[22:17:00.011] Timer tick, asking Buildbucket for the build status
[22:17:00.555] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:00.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:21:0) after 1m0s
[22:18:01.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:21:0)
[22:18:01.069] Timer tick, asking Buildbucket for the build status
[22:18:01.338] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:01.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:22:0) after 1m0s
[22:19:01.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:22:0)
[22:19:01.387] Timer tick, asking Buildbucket for the build status
[22:19:02.216] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:02.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:23:0) after 1m0s
[22:20:02.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:23:0)
[22:20:02.262] Timer tick, asking Buildbucket for the build status
[22:20:02.402] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:02.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:24:0) after 1m0s
[22:21:02.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:24:0)
[22:21:02.493] Timer tick, asking Buildbucket for the build status
[22:21:02.631] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:02.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:25:0) after 1m0s
[22:22:02.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:25:0)
[22:22:02.767] Timer tick, asking Buildbucket for the build status
[22:22:02.922] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:02.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:26:0) after 1m0s
[22:23:02.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:26:0)
[22:23:02.915] Timer tick, asking Buildbucket for the build status
[22:23:03.006] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:03.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:27:0) after 1m0s
[22:24:03.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:27:0)
[22:24:03.096] Timer tick, asking Buildbucket for the build status
[22:24:03.792] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:03.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:28:0) after 1m0s
[22:25:03.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:28:0)
[22:25:03.966] Timer tick, asking Buildbucket for the build status
[22:25:06.306] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:06.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:29:0) after 1m0s
[22:26:06.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:29:0)
[22:26:06.357] Timer tick, asking Buildbucket for the build status
[22:26:06.418] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:06.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:30:0) after 1m0s
[22:27:06.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:30:0)
[22:27:06.398] Timer tick, asking Buildbucket for the build status
[22:27:06.486] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:06.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:31:0) after 1m0s
[22:28:06.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:31:0)
[22:28:06.541] Timer tick, asking Buildbucket for the build status
[22:28:06.742] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:06.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:32:0) after 1m0s
[22:29:06.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:32:0)
[22:29:06.889] Timer tick, asking Buildbucket for the build status
[22:29:07.361] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:07.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:33:0) after 1m0s
[22:30:07.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:33:0)
[22:30:07.475] Timer tick, asking Buildbucket for the build status
[22:30:07.546] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:07.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:34:0) after 1m0s
[22:31:07.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:34:0)
[22:31:07.619] Timer tick, asking Buildbucket for the build status
[22:31:07.682] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:07.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:35:0) after 1m0s
[22:32:07.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:35:0)
[22:32:07.922] Timer tick, asking Buildbucket for the build status
[22:32:08.034] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:08.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:36:0) after 1m0s
[22:33:08.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:36:0)
[22:33:08.416] Timer tick, asking Buildbucket for the build status
[22:33:08.677] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:08.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:37:0) after 1m0s
[22:34:08.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:37:0)
[22:34:08.823] Timer tick, asking Buildbucket for the build status
[22:34:09.208] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:09.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:38:0) after 1m0s
[22:35:09.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:38:0)
[22:35:09.182] Timer tick, asking Buildbucket for the build status
[22:35:09.462] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:09.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:39:0) after 1m0s
[22:36:09.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:39:0)
[22:36:09.511] Timer tick, asking Buildbucket for the build status
[22:36:09.768] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:09.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:40:0) after 1m0s
[22:37:09.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:40:0)
[22:37:09.758] Timer tick, asking Buildbucket for the build status
[22:37:09.854] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:09.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:41:0) after 1m0s
[22:38:09.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:41:0)
[22:38:09.830] Timer tick, asking Buildbucket for the build status
[22:38:09.953] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:09.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:42:0) after 1m0s
[22:39:10.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:42:0)
[22:39:10.026] Timer tick, asking Buildbucket for the build status
[22:39:10.250] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:10.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:43:0) after 1m0s
[22:40:10.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:43:0)
[22:40:10.292] Timer tick, asking Buildbucket for the build status
[22:40:10.448] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:10.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:44:0) after 1m0s
[22:41:10.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:44:0)
[22:41:10.496] Timer tick, asking Buildbucket for the build status
[22:41:10.660] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:10.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:45:0) after 1m0s
[22:42:10.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:45:0)
[22:42:10.683] Timer tick, asking Buildbucket for the build status
[22:42:10.791] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:10.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:46:0) after 1m0s
[22:43:10.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:46:0)
[22:43:10.838] Timer tick, asking Buildbucket for the build status
[22:43:10.942] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:10.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:47:0) after 1m0s
[22:44:10.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:47:0)
[22:44:10.970] Timer tick, asking Buildbucket for the build status
[22:44:11.176] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:11.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:48:0) after 1m0s
[22:45:11.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:48:0)
[22:45:11.266] Timer tick, asking Buildbucket for the build status
[22:45:11.520] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:11.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:49:0) after 1m0s
[22:46:11.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:49:0)
[22:46:11.567] Timer tick, asking Buildbucket for the build status
[22:46:12.205] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:12.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:50:0) after 1m0s
[22:47:12.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:50:0)
[22:47:12.367] Timer tick, asking Buildbucket for the build status
[22:47:12.593] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:12.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:51:0) after 1m0s
[22:48:12.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:51:0)
[22:48:12.584] Timer tick, asking Buildbucket for the build status
[22:48:12.806] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:12.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:52:0) after 1m0s
[22:49:12.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:52:0)
[22:49:12.877] Timer tick, asking Buildbucket for the build status
[22:49:13.044] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:13.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:53:0) after 1m0s
[22:50:13.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:53:0)
[22:50:13.057] Timer tick, asking Buildbucket for the build status
[22:50:13.175] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:13.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:54:0) after 1m0s
[22:51:13.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:54:0)
[22:51:13.205] Timer tick, asking Buildbucket for the build status
[22:51:13.314] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:13.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:55:0) after 1m0s
[22:52:13.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:55:0)
[22:52:13.381] Timer tick, asking Buildbucket for the build status
[22:52:13.758] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:13.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:56:0) after 1m0s
[22:53:13.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:56:0)
[22:53:13.791] Timer tick, asking Buildbucket for the build status
[22:53:14.692] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:14.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:57:0) after 1m0s
[22:54:14.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:57:0)
[22:54:14.736] Timer tick, asking Buildbucket for the build status
[22:54:15.620] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:15.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:58:0) after 1m0s
[22:55:15.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:58:0)
[22:55:15.699] Timer tick, asking Buildbucket for the build status
[22:55:15.899] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:15.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:59:0) after 1m0s
[22:56:15.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:59:0)
[22:56:15.945] Timer tick, asking Buildbucket for the build status
[22:56:16.086] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:16.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:60:0) after 1m0s
[22:57:16.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:60:0)
[22:57:16.133] Timer tick, asking Buildbucket for the build status
[22:57:16.226] Build 8858183284823092896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:16.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023613346802086144:61:0) after 1m0s
[22:57:58.041] Received PubSub notification, asking Buildbucket for the build status
[22:57:58.100] Build 8858183284823092896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:57:58.100] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610578677213390",
"created_by": "project:chromium",
"created_ts": "1610575133453184",
"id": "8858183284823092896",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\"}}",
"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\": \"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\", \"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-7-gais\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44577, \"got_angle_revision\": \"47e9aed2af83c0c3f684a804156c3b876a58a1bf\", \"got_dawn_revision\": \"111ba65a5e3dbd23a58dd63991e92e9300f44d13\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\", \"got_revision_cp\": \"refs/heads/master@{#843200}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"a1598e5821fc3ca0d2ca8928d41cdd3a12114c7a\", \"got_v8_revision_cp\": \"refs/heads/8.9.266@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"9f1169b6e113f9832cddfcbaba693f8e1e9bef8f\", \"swarm_hashes_refs/heads/master(at){#843200}_without_patch\": {\"absl_hardening_tests\": \"62f92c2f42bd63984aa1d2319ea09cc9196e5630\", \"accessibility_unittests\": \"7f8ca9ff755ef035842498ef58a37cc5afc2057e\", \"angle_unittests\": \"8c53e6daa331efc927aff49fe2a15ee320e29062\", \"app_list_unittests\": \"c84f287178a1bf53b83aeec2bbcacc48a26f7c4b\", \"app_shell_unittests\": \"0cd9a4241ad08b1c5c47d9d791165bfaff64ab21\", \"ash_unittests\": \"8adf293c929232408d0d0797c943250b24647164\", \"aura_unittests\": \"b520b1598fb687c005c13af6a3cfed3d38a86323\", \"base_unittests\": \"6b8428a73f29016949b8791f63663a8d94654e69\", \"base_util_unittests\": \"af8c13e25fb3c04577d3e179e7b0df281930006f\", \"blink_common_unittests\": \"344eebb417945310eef389bd55ec739a1c3509f0\", \"blink_fuzzer_unittests\": \"e9b3848e80c25be55409abac6a2fa3dc979f24cf\", \"blink_heap_unittests\": \"f02fa4348d1ee1ec83e9b2a857e22dd5dff7299b\", \"blink_platform_unittests\": \"420d840e9aad3554ef192b937e8e9393acf1146e\", \"blink_unittests\": \"cbeb218301ebe9a121668a33c5574c5d06369b23\", \"boringssl_crypto_tests\": \"816be2273805eb549bcbfdf03c17a0591743bfe5\", \"boringssl_ssl_tests\": \"e952c1d3466027cf65c2fe8c865cfef18ec08a6a\", \"browser_tests\": \"6769b5120fe54aa1e8eb93bc4f6dbb7f7839f9dd\", \"capture_unittests\": \"fac8ae5725fc83a3fa955049f175bc2724963701\", \"cast_unittests\": \"52e268b148a6fdf13e51eff2e662699317854c78\", \"cc_unittests\": \"fc7d67af2b19189217aee66deaf1166e56b3e0fd\", \"chrome_app_unittests\": \"20989deca9bd2065dd0190746e7da411d330c85f\", \"chromedriver_unittests\": \"c8ee16c816c1740e77488314d1e3442c1a1752d7\", \"chromeos_components_unittests\": \"ec9254fefe05ab29b63573da6118904eb66b4312\", \"chromeos_unittests\": \"f292dda559cfa15f68c33b5d7ce2f5c56385eab3\", \"components_browsertests\": \"f160fa9a66e8c4b5c803034f244ad4a15fbcb5be\", \"components_unittests\": \"1011a29708e537906abeef68a7593d1fd4348fee\", \"compositor_unittests\": \"fe5921b481006641941015570924a69caf2c326b\", \"content_browsertests\": \"f151f6b80532d822e24e6dfdf2958b7e448609f2\", \"content_unittests\": \"60c3ae90e2434518d5fae343aeae59b8d5839405\", \"crashpad_tests\": \"cbecedccad5af03bd8894cfb686f7ad396df64a5\", \"crypto_unittests\": \"c4fd20bb4add18fa0e65ec217d6074ae046029b2\", \"dbus_unittests\": \"81bc87df3d3af29c59fbaa58db5de6a9333f24a9\", \"device_unittests\": \"758bdae186d3372c3e188ea1e3ff64545d4f504b\", \"display_unittests\": \"77a3f16480fc2c4e4acee6e11f600615ab4e4ba5\", \"events_unittests\": \"e7f4bdd6da96da25ac9e0f723a5efec387b5bfa0\", \"exo_unittests\": \"2d2a35e4074bf36ff0464f30dadaa3fe588f694d\", \"extensions_browsertests\": \"2998ccff7e95c32c58ea70452f36f97544388ebb\", \"extensions_unittests\": \"0626ee8350caf61e0e13595cf97ca1f7fa84f19a\", \"filesystem_service_unittests\": \"907b05eff865f66ddcc7c18269c9e8cc328685f1\", \"gcm_unit_tests\": \"21ff583378eca7843e4e155efe3b1299f5366be1\", \"gfx_unittests\": \"0201a4a31601d51b8fb6e83694b3cdd1987930bf\", \"gin_unittests\": \"67e8c10438a7334ce23b6820e1501adc5aee18dd\", \"gl_unittests_ozone\": \"0438b62644bfe5ee9194bc234ce3f6f86298c1e9\", \"google_apis_unittests\": \"1259b59ac9fee3819bad5713341023cb847b1c65\", \"gpu_unittests\": \"fe17e37560cdc48abbfd5e0be14d99b35ede6409\", \"gwp_asan_unittests\": \"567dbef64b2a1483805149f3efb2800cba57198d\", \"interactive_ui_tests\": \"746447dcc2cc857aecad5dafac3a3e1d5f6d9a11\", \"ipc_tests\": \"6cfe948ef2af3c17383bb9222eb7f7d2d3c1bf6a\", \"jingle_unittests\": \"a6a5f94b8ceae6150a2fbe1948dd9036ab3a030d\", \"keyboard_unittests\": \"78f440a393d596aecbd575afa03b13c3ef1f91f6\", \"latency_unittests\": \"3227888fa9e978eb29427a38e7fb783e79467308\", \"libjingle_xmpp_unittests\": \"d472334aa9308b7018b209a5a7b8f70e6cfbd5bc\", \"liburlpattern_unittests\": \"3308020d5080bdce2b7851cc6710e6d5b9a23574\", \"media_blink_unittests\": \"ea6c02bb168be8b2c4bd37f6ad34fcb542b0e8da\", \"media_unittests\": \"d20bbcc9fff68efe9f7e29f8bc57ef083f87bcd4\", \"message_center_unittests\": \"108edaff9cd2570dcba7488154d8485c5a05a788\", \"midi_unittests\": \"e25868d63d1f7ea56086b39a8b0ea9f93de6d638\", \"mojo_core_unittests\": \"2b063630044b12f0ffd9fafb8eff1246ae6c7ac8\", \"mojo_unittests\": \"608c63aa335e9ec2994f7c2ccc0e34560b5aba96\", \"nacl_helper_nonsfi_unittests\": \"36ec103c5163cb41e8ea906512ea8c7d1dcffd1a\", \"nacl_loader_unittests\": \"8749d0ae4356ffd9be047a3a4e9e2f0591297af6\", \"native_theme_unittests\": \"456b95b76ccb2f3d549e981a194f7f29ae5f2f56\", \"net_unittests\": \"e5f529ef3e8f9a7e412466b2858620280081de7f\", \"ozone_gl_unittests\": \"a731a0becad934aa370df5e4d23691a0ef4e09e3\", \"ozone_unittests\": \"d4594b37c3f92d6a9349b476fe03c9a5557f572d\", \"ozone_x11_unittests\": \"b9a06f55d7b484f4f7a32359206d3a10f0e580f3\", \"pdf_unittests\": \"eabc8ce5008909f897670e2c0cebb94a8413262d\", \"perfetto_unittests\": \"c677ce025ac943fc608ee2bbc25af7975077e0df\", \"ppapi_unittests\": \"7681676e179dd8f1338c702ff1586fe13257d3c1\", \"printing_unittests\": \"ffee0b810c3c49aa941156278492ccf82809aeaa\", \"remoting_unittests\": \"0f920038d82e176aa607cdaffe3b58affcdfc489\", \"sandbox_linux_unittests\": \"ff87d6be34dd74045d3a59893a8d73d7c02c3ea7\", \"service_manager_unittests\": \"2114e6e201fce6644af645073345361bc9374301\", \"services_unittests\": \"db3afc21287f16b1e4442f7c3191cf38a6adfe7c\", \"shell_dialogs_unittests\": \"23579c8e0fe033263b47772b3d980f4c35c629a4\", \"shell_encryption_unittests\": \"d7695cc3e1aec1a327aa687a7ddda3bd233dcd60\", \"skia_unittests\": \"7eed47d772dc8fb41e47710685cd7b0e3a220a3d\", \"snapshot_unittests\": \"78f4fe4727b98e76d112427dfd1fbf2008ffd32a\", \"sql_unittests\": \"ace2aa36b3ba26467c03238a290a3794b671e525\", \"storage_unittests\": \"9b00b97f4e8d9a2610d505e533fffdf87236e9f8\", \"sync_integration_tests\": \"87bceaf32e4aa1e1ba6daa8ccbff6c4779c709f5\", \"traffic_annotation_auditor_unittests\": \"c454bc45e3663ab6f21988b1b29fc82f5211005b\", \"ui_base_unittests\": \"041588d386d094b611a14f754abac91793e4fa00\", \"ui_chromeos_unittests\": \"3e660b216c1a29b15eb522ac6fbbdc5e278a7d83\", \"ui_touch_selection_unittests\": \"544538de7242934ec6bde2c1721462098c348519\", \"unit_tests\": \"34179a65100f3c207f475e929ffc948936f744ec\", \"url_unittests\": \"98f523fb023594d99c1e39240ad0087284030049\", \"usage_time_limit_unittests\": \"ecc2e9801ff6d11d4316b334ba1b2e91ce39c8ae\", \"views_unittests\": \"bb712199299419cf753b4e85a818d52e87dcd759\", \"viz_unittests\": \"a0e4b44b32f866081323e36be9d5612d3dc2e618\", \"wayland_client_perftests\": \"45c3223ce585e7dd4b79569f98f17fb1f10afc6d\", \"wm_unittests\": \"daec2afe9d61d82ad06650902e4243b4b16ffcc4\", \"wtf_unittests\": \"56247f59b5c54d6f1692c5c3ec4b95b63ff971f5\", \"zlib_unittests\": \"0df125211e015fefae6fab8b20bb9ec2a25a5260\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0fb1f456593ae2600bf9c9616e0e5dcc3c87ffc9d4d6fb93e9350aac8ae4516c_v2\", \"builder_1b9daa149b4790c306d4115e8498bce8e4004f87fdf8195a8bb15f493be69c40_v2\", \"builder_8c23a72064650b4b1d9380637a287eed4ae2e2a2092116afea022d28ee09b5dd_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a038fb555685f584149318062f53e0831a488e02c6d40fe1e156bb7cef46d2c7_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_b21beaca446334e07b00c4982fb2d009cad33fb091bf002422bc83c400c91f3b_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-7-gais\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610575162262515",
"status": "COMPLETED",
"status_changed_ts": "1610578677586388",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44577",
"builder:linux-chromeos-rel",
"buildset:commit/git/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f1169b6e113f9832cddfcbaba693f8e1e9bef8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023613346802086144",
"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/8858183284823092896/+/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:511693c5eeaafd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610578677598272",
"url": "https://ci.chromium.org/b/8858183284823092896",
"utcnow_ts": "1610578678091823"
}
}
[22:57:58.100] Invocation finished in 59m5.917696684s with status SUCCEEDED