[13:20:57.666] New invocation is queued and will start shortly
[13:20:59.044] Starting the invocation (attempt 1)
[13:20:59.100] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:20:59.100] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:20:59.100] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027269809467877584",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027269809467877584",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"gitiles_ref:refs/heads/master"
]
}
[13:21:00.174] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607088059205595",
"id": "8861839747189349936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607088059907040",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43440",
"builder:linux-chromeos-rel",
"buildset:commit/git/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027269809467877584",
"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/8861839747189349936/+/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": "1607088059907532",
"url": "https://ci.chromium.org/b/8861839747189349936",
"utcnow_ts": "1607088060074508"
}
}
[13:21:00.174] Task URL: https://ci.chromium.org/b/8861839747189349936
[13:21:00.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:2:0) after 1m0s
[13:21:03.577] Received PubSub notification, asking Buildbucket for the build status
[13:21:03.647] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:00.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:2:0)
[13:22:00.887] Timer tick, asking Buildbucket for the build status
[13:22:01.100] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:01.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:4:0) after 1m0s
[13:23:02.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:4:0)
[13:23:02.295] Timer tick, asking Buildbucket for the build status
[13:23:02.379] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:02.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:5:0) after 1m0s
[13:24:02.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:5:0)
[13:24:02.647] Timer tick, asking Buildbucket for the build status
[13:24:03.791] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:03.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:6:0) after 1m0s
[13:25:03.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:6:0)
[13:25:03.849] Timer tick, asking Buildbucket for the build status
[13:25:04.024] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:7:0) after 1m0s
[13:26:04.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:7:0)
[13:26:04.034] Timer tick, asking Buildbucket for the build status
[13:26:04.181] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:04.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:8:0) after 1m0s
[13:27:04.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:8:0)
[13:27:04.215] Timer tick, asking Buildbucket for the build status
[13:27:04.325] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:04.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:9:0) after 1m0s
[13:28:04.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:9:0)
[13:28:04.471] Timer tick, asking Buildbucket for the build status
[13:28:05.502] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:05.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:10:0) after 1m0s
[13:29:05.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:10:0)
[13:29:05.646] Timer tick, asking Buildbucket for the build status
[13:29:05.789] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:05.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:11:0) after 1m0s
[13:30:06.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:11:0)
[13:30:06.076] Timer tick, asking Buildbucket for the build status
[13:30:06.217] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:06.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:12:0) after 1m0s
[13:31:06.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:12:0)
[13:31:06.837] Timer tick, asking Buildbucket for the build status
[13:31:07.651] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:07.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:13:0) after 1m0s
[13:32:07.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:13:0)
[13:32:07.888] Timer tick, asking Buildbucket for the build status
[13:32:08.009] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:08.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:14:0) after 1m0s
[13:33:08.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:14:0)
[13:33:08.133] Timer tick, asking Buildbucket for the build status
[13:33:08.361] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:08.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:15:0) after 1m0s
[13:34:08.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:15:0)
[13:34:08.482] Timer tick, asking Buildbucket for the build status
[13:34:08.684] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:08.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:16:0) after 1m0s
[13:35:08.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:16:0)
[13:35:08.750] Timer tick, asking Buildbucket for the build status
[13:35:08.821] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:08.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:17:0) after 1m0s
[13:36:08.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:17:0)
[13:36:08.845] Timer tick, asking Buildbucket for the build status
[13:36:09.006] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:09.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:18:0) after 1m0s
[13:37:09.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:18:0)
[13:37:09.032] Timer tick, asking Buildbucket for the build status
[13:37:09.097] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:09.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:19:0) after 1m0s
[13:38:09.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:19:0)
[13:38:09.152] Timer tick, asking Buildbucket for the build status
[13:38:09.275] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:09.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:20:0) after 1m0s
[13:39:09.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:20:0)
[13:39:09.330] Timer tick, asking Buildbucket for the build status
[13:39:11.431] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:11.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:21:0) after 1m0s
[13:40:11.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:21:0)
[13:40:11.418] Timer tick, asking Buildbucket for the build status
[13:40:11.489] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:11.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:22:0) after 1m0s
[13:41:11.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:22:0)
[13:41:11.489] Timer tick, asking Buildbucket for the build status
[13:41:11.601] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:11.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:23:0) after 1m0s
[13:42:11.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:23:0)
[13:42:11.628] Timer tick, asking Buildbucket for the build status
[13:42:11.749] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:11.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:24:0) after 1m0s
[13:43:11.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:24:0)
[13:43:11.775] Timer tick, asking Buildbucket for the build status
[13:43:11.954] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:11.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:25:0) after 1m0s
[13:44:12.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:25:0)
[13:44:12.309] Timer tick, asking Buildbucket for the build status
[13:44:12.588] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:12.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:26:0) after 1m0s
[13:45:12.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:26:0)
[13:45:12.754] Timer tick, asking Buildbucket for the build status
[13:45:12.833] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:12.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:27:0) after 1m0s
[13:46:12.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:27:0)
[13:46:12.909] Timer tick, asking Buildbucket for the build status
[13:46:13.005] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:13.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:28:0) after 1m0s
[13:47:13.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:28:0)
[13:47:13.059] Timer tick, asking Buildbucket for the build status
[13:47:13.275] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:13.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:29:0) after 1m0s
[13:48:13.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:29:0)
[13:48:13.368] Timer tick, asking Buildbucket for the build status
[13:48:13.439] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:13.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:30:0) after 1m0s
[13:49:13.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:30:0)
[13:49:13.476] Timer tick, asking Buildbucket for the build status
[13:49:13.547] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:13.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:31:0) after 1m0s
[13:50:13.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:31:0)
[13:50:13.738] Timer tick, asking Buildbucket for the build status
[13:50:13.815] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:32:0) after 1m0s
[13:51:14.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:32:0)
[13:51:14.048] Timer tick, asking Buildbucket for the build status
[13:51:14.157] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:14.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:33:0) after 1m0s
[13:52:14.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:33:0)
[13:52:14.333] Timer tick, asking Buildbucket for the build status
[13:52:14.387] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:14.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:34:0) after 1m0s
[13:53:14.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:34:0)
[13:53:14.558] Timer tick, asking Buildbucket for the build status
[13:53:15.046] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:15.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:35:0) after 1m0s
[13:54:15.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:35:0)
[13:54:15.099] Timer tick, asking Buildbucket for the build status
[13:54:15.176] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:15.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:36:0) after 1m0s
[13:55:15.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:36:0)
[13:55:15.240] Timer tick, asking Buildbucket for the build status
[13:55:15.304] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:15.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:37:0) after 1m0s
[13:56:15.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:37:0)
[13:56:15.525] Timer tick, asking Buildbucket for the build status
[13:56:15.650] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:15.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:38:0) after 1m0s
[13:57:15.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:38:0)
[13:57:15.768] Timer tick, asking Buildbucket for the build status
[13:57:15.831] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:15.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:39:0) after 1m0s
[13:58:15.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:39:0)
[13:58:15.942] Timer tick, asking Buildbucket for the build status
[13:58:16.157] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:16.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:40:0) after 1m0s
[13:59:16.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:40:0)
[13:59:16.385] Timer tick, asking Buildbucket for the build status
[13:59:16.449] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:16.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:41:0) after 1m0s
[14:00:16.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:41:0)
[14:00:16.588] Timer tick, asking Buildbucket for the build status
[14:00:16.736] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:16.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:42:0) after 1m0s
[14:01:16.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:42:0)
[14:01:16.884] Timer tick, asking Buildbucket for the build status
[14:01:17.055] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:17.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:43:0) after 1m0s
[14:02:17.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:43:0)
[14:02:17.229] Timer tick, asking Buildbucket for the build status
[14:02:17.519] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:17.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:44:0) after 1m0s
[14:03:17.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:44:0)
[14:03:17.741] Timer tick, asking Buildbucket for the build status
[14:03:18.232] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:18.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:45:0) after 1m0s
[14:04:18.348] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:45:0)
[14:04:18.348] Timer tick, asking Buildbucket for the build status
[14:04:18.777] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:18.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:46:0) after 1m0s
[14:05:18.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:46:0)
[14:05:18.818] Timer tick, asking Buildbucket for the build status
[14:05:19.041] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:19.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:47:0) after 1m0s
[14:06:19.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:47:0)
[14:06:19.188] Timer tick, asking Buildbucket for the build status
[14:06:19.342] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:19.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:48:0) after 1m0s
[14:07:19.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:48:0)
[14:07:19.622] Timer tick, asking Buildbucket for the build status
[14:07:19.724] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:19.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:49:0) after 1m0s
[14:08:19.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:49:0)
[14:08:19.910] Timer tick, asking Buildbucket for the build status
[14:08:20.015] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:20.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:50:0) after 1m0s
[14:09:20.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:50:0)
[14:09:20.083] Timer tick, asking Buildbucket for the build status
[14:09:20.205] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:20.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:51:0) after 1m0s
[14:10:20.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:51:0)
[14:10:20.271] Timer tick, asking Buildbucket for the build status
[14:10:20.434] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:20.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:52:0) after 1m0s
[14:11:20.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:52:0)
[14:11:20.560] Timer tick, asking Buildbucket for the build status
[14:11:20.694] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:20.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:53:0) after 1m0s
[14:12:21.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:53:0)
[14:12:21.057] Timer tick, asking Buildbucket for the build status
[14:12:21.487] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:21.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:54:0) after 1m0s
[14:13:21.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:54:0)
[14:13:21.641] Timer tick, asking Buildbucket for the build status
[14:13:21.767] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:21.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:55:0) after 1m0s
[14:14:21.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:55:0)
[14:14:21.842] Timer tick, asking Buildbucket for the build status
[14:14:21.962] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:21.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:56:0) after 1m0s
[14:15:22.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:56:0)
[14:15:22.094] Timer tick, asking Buildbucket for the build status
[14:15:22.266] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:22.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:57:0) after 1m0s
[14:16:22.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:57:0)
[14:16:22.390] Timer tick, asking Buildbucket for the build status
[14:16:22.654] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:22.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:58:0) after 1m0s
[14:17:22.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:58:0)
[14:17:22.710] Timer tick, asking Buildbucket for the build status
[14:17:22.919] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:22.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:59:0) after 1m0s
[14:18:23.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:59:0)
[14:18:23.044] Timer tick, asking Buildbucket for the build status
[14:18:23.234] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:23.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:60:0) after 1m0s
[14:19:23.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:60:0)
[14:19:23.290] Timer tick, asking Buildbucket for the build status
[14:19:23.411] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:23.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:61:0) after 1m0s
[14:20:23.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:61:0)
[14:20:23.871] Timer tick, asking Buildbucket for the build status
[14:20:24.264] Build 8861839747189349936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:24.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027269809467877584:62:0) after 1m0s
[14:21:09.167] Received PubSub notification, asking Buildbucket for the build status
[14:21:09.267] Build 8861839747189349936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:21:09.267] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607091665231187",
"created_by": "project:chromium",
"created_ts": "1607088059205595",
"id": "8861839747189349936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\"}}",
"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\": \"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\", \"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-140-6k2u\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43440, \"got_angle_revision\": \"1ad5791d97bea23f092b206679a56302defa378f\", \"got_dawn_revision\": \"ab5821d0166feabf7690b7d2399ad36870501e72\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\", \"got_revision_cp\": \"refs/heads/master@{#833693}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"ab473c140fe5b583fe97ecb319840f23168f425a\", \"got_v8_revision_cp\": \"refs/heads/8.9.99@{#1}\", \"got_webrtc_revision\": \"c3c81297365b6a293480f7af7ce5df632324b9e1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32768}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce0441eda4b41ae42957a2da21ca4b45b99e6f7\", \"swarm_hashes_refs/heads/master(at){#833693}_without_patch\": {\"absl_hardening_tests\": \"6d06764c2431c4875220577096afaa6c709f8332\", \"accessibility_unittests\": \"293661fa43e52f85102f74d10c7b18bfbcbce1b7\", \"angle_unittests\": \"416a3ada035d87cca35d6b70519fea97a574807d\", \"app_list_unittests\": \"09e0b920359876edfedf663082f77d8b512fac1f\", \"app_shell_unittests\": \"b5dac2c06f40eb36a56b7510f18934aea12bc625\", \"ash_unittests\": \"8e30d0681927b0bf0128623d814b99625af3d638\", \"aura_unittests\": \"d16994d606a00b3e804c5735e549fa470183c2bf\", \"base_unittests\": \"b31bc4b931ba53e39be815a2cce24498dde32f36\", \"base_util_unittests\": \"2e8be2d69133587afc998c3423a631d6546c4b62\", \"blink_common_unittests\": \"8226e45706bd254addd6906bffd86738a0158548\", \"blink_fuzzer_unittests\": \"16e8d6a4e39758a33bc24c12b4ec097de6257156\", \"blink_heap_unittests\": \"4dfc93d2efc4c335ccf4f3f72b31d02608cfd715\", \"blink_platform_unittests\": \"03db14b705a8dbc8b41934cb344864cc22ca1f2b\", \"blink_unittests\": \"6cdb12d11665786241bd4d705bcfdbadd7e70bc5\", \"boringssl_crypto_tests\": \"049e786d677d2c3d67cd485881ca983eb5f374ef\", \"boringssl_ssl_tests\": \"67f17125db496d440235f2b7a1d398485a22677e\", \"browser_tests\": \"1ffd35df802304be0162848c7c6280120456c224\", \"capture_unittests\": \"4b68beaa2a5865e56ad0ca2b45750e45f58ab35c\", \"cast_unittests\": \"88bd278ac1ed643ce6a86b642b13ef51b25cd687\", \"cc_unittests\": \"f276281b4640e375e2ede9aaae9d9255a8f2bd71\", \"chrome_app_unittests\": \"320af8773c531c84c739c5ac759fc3edf019886a\", \"chromedriver_unittests\": \"0f2d14df784ab057914e49171a0c4740772ec0d1\", \"chromeos_components_unittests\": \"a95d505bbd2ac5df31549f37dbc3d6c59f3aaf17\", \"chromeos_unittests\": \"5c1c9dda2501ee1b1ec81de96b59bd12804ebebb\", \"components_browsertests\": \"e34282b533f310b314c3ec2dc03300f5385705cf\", \"components_unittests\": \"e39528e5349de0ebb9f25bcfc4e45dd06b1e3f67\", \"compositor_unittests\": \"315ae945a0b61bac1b194b015757d4a1ee1d9760\", \"content_browsertests\": \"8b0081a6b2d9bb7c40f09696b7eb4777be199425\", \"content_unittests\": \"c82adea64199f8e0adaa18996bf4f7bf3a0464a1\", \"crashpad_tests\": \"63be958f8cdb8566e6a62bb9dc96485914758a02\", \"crypto_unittests\": \"4a56221996fc424edd008d1874ec6b0d5f2cbd8f\", \"dbus_unittests\": \"26c9bc7318d656d92f4114c8a7e79a7104a642fe\", \"device_unittests\": \"9476d563b3fff96e27972573821204f2b2923ab3\", \"display_unittests\": \"22be6eb160b82ee5433fef38b96593d7382cdfb2\", \"events_unittests\": \"ca54f997c9c9227481421c1f0835f54685d3307c\", \"exo_unittests\": \"4496690a0628aebb2ce8982ee1c9508c1e7166a7\", \"extensions_browsertests\": \"2c5527617077c3936410b7e4816a70865e5fe10f\", \"extensions_unittests\": \"c09997057fb8eb5ea1af759e360f08818edc6397\", \"filesystem_service_unittests\": \"14d98e911bd890fa0e82b0931bfdddfdf6b28ab2\", \"gcm_unit_tests\": \"69c49139bfa7132b700c18974183f75640b5b274\", \"gfx_unittests\": \"14723d68cafbea10c36582bde84daaec630c0dea\", \"gin_unittests\": \"9b03a7ac7a7c041fa7aa5e515d8ace38f3ad1067\", \"gl_unittests_ozone\": \"2e78aa7437f8dbc2b96f09fa44c598dc0c27ccfd\", \"google_apis_unittests\": \"01f080182062761210a710a6bc4a21c4cf206dda\", \"gpu_unittests\": \"c34af37d214da8bfa581ac4ea5f50b9d6a75f4da\", \"gwp_asan_unittests\": \"330bb62c3567efde1b7de4eb6768a83b996fa946\", \"interactive_ui_tests\": \"75a3bbd6e5f5f94a2eb238f14fdebd5f373c5223\", \"ipc_tests\": \"f3dcfd406da1e3ff61da80a5e49734af54efe535\", \"jingle_unittests\": \"91224c76ee4c5ae4b07f7f937eedfd73058633e7\", \"keyboard_unittests\": \"7eef56560c4ea334eaab8bf8f97710577f2aacde\", \"latency_unittests\": \"175a6ae857f6d130bafa51ad94e94651c93a41dd\", \"libjingle_xmpp_unittests\": \"27b1e5268a821d1c88a5945781bd5f6bdb0ffe50\", \"liburlpattern_unittests\": \"88c1d773d027f8cb642ae0b597ee9201cdc53759\", \"media_blink_unittests\": \"2e04cd76f1868cca4f4bb7196d4d4dc3ee3e6ce4\", \"media_unittests\": \"61380a475a780cf6e03eb43d32a5fe09a87b8cda\", \"message_center_unittests\": \"1b9d45f0fb8ed9e8edaddffa5a5efd2ed4e785fd\", \"midi_unittests\": \"a91db25bd493154b235041cffb681a8c456e6113\", \"mojo_core_unittests\": \"d43b3498cfe1e7d077eeaa13361fe3d972f57aea\", \"mojo_unittests\": \"e9b95733222b4f9dd224574da48817126c055166\", \"nacl_helper_nonsfi_unittests\": \"2c1ef58341ff106449306546e51708e7885584e7\", \"nacl_loader_unittests\": \"fd5edd4028cdcbf92a0e2439d0a810e4c0ececc6\", \"native_theme_unittests\": \"ab4b4c5c1d28b97da162baf3905c6b6996896541\", \"net_unittests\": \"6c0cd9e7b972d468185b57262c20308aae4a7ab2\", \"ozone_gl_unittests\": \"e55ba800c34e778cbe107c413d78d5f2a4b70b13\", \"ozone_unittests\": \"47e644eacade48529263d6091f084bfcedbb1bed\", \"ozone_x11_unittests\": \"87267ce5380f024b0578725af7faef60848dcb2e\", \"pdf_unittests\": \"cfdd711fab6f88c56909b69f9eca43baaf8ce95f\", \"perfetto_unittests\": \"d5e8fd00e704b1d16ddc1eda81de9c208d670a1a\", \"ppapi_unittests\": \"6f1afce4e0f3ec7d552963dbf8550e022bcbf0bd\", \"printing_unittests\": \"be5e7586c9e1a20845e26fe5c54b17e7de7ea0df\", \"remoting_unittests\": \"44832ca5329878c60ecd546b8abf8fdfd4d4a560\", \"sandbox_linux_unittests\": \"a4a3614355f5c1a2e341fac584f8f7b8a78237c8\", \"service_manager_unittests\": \"54e6d08bb3637d4f513f6ebb58ad49c8cb3729f4\", \"services_unittests\": \"f20f915d350cf85ae311877c3bdfa235a7c1c788\", \"shell_dialogs_unittests\": \"dff88d4036bd10c2d7e840ba9331ba79ae9159d0\", \"shell_encryption_unittests\": \"c83859fef7b69e7ce05ea1f25fc45534d5b3a290\", \"skia_unittests\": \"884497b53ab7990aeb6ebea59f0e9684546bdf3d\", \"snapshot_unittests\": \"255aedddacdc755e6f4402082e7a269ab4efc11f\", \"sql_unittests\": \"336c6b6d609f688633e14a938137cb1bc1426a88\", \"storage_unittests\": \"cd36bc39315edbb8bdbfceaafdd5196e59f50353\", \"sync_integration_tests\": \"c93cc178af0310bb8cb72e5f321540584e4336ed\", \"traffic_annotation_auditor_unittests\": \"a3ccf48689d4fe846c0f89a7b80b22ba561e3b86\", \"ui_base_unittests\": \"a571fd5880d6521dc1670a19dfe15702c36ce9bf\", \"ui_chromeos_unittests\": \"a77bcdfdcbb8b18e2889805f15967ef50767931e\", \"ui_touch_selection_unittests\": \"09cd9b799c379b0f9b6fab44eb89319532556d5c\", \"unit_tests\": \"ce2e2b9210a2ebaa2421c6336a0c28b726f28057\", \"url_unittests\": \"23231d68c3ccf0c19814bd5333386f7cefc1f49c\", \"usage_time_limit_unittests\": \"2e755c378a86de361f80e473b6a1cc8ec2b206a9\", \"views_unittests\": \"0241f57a24e8160d891cdd5e3d34820fd3937143\", \"viz_unittests\": \"ed93caaec87330841724d7ba92376688f4be5007\", \"wayland_client_perftests\": \"a9b07ff9244a3192c1204cb585d95f5b9a20138a\", \"wm_unittests\": \"7c896a583bcfef0e2beb12c45c01bc0c3662a885\", \"wtf_unittests\": \"2523a07f3dde476ea7ad3e962c9eb88172338d9c\", \"zlib_unittests\": \"eaff9779b98c7fb14dd536444ca3c1a054cf45d1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_49cfd195f2a0cca8cb73cb69371f0b72c7028f18b7affdf7b58db7400786f84c_v2\", \"builder_6cd5ded23cefc640ae511b7a529237cb2561e39c1afb96e401c63016ddcf35a6_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_a50faa98e00aefb88600aa330f70664f9b4b54ee0ff51cb8b03a7f0a0b737b38_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-140-6k2u\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607088062653036",
"status": "COMPLETED",
"status_changed_ts": "1607091666202087",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43440",
"builder:linux-chromeos-rel",
"buildset:commit/git/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce0441eda4b41ae42957a2da21ca4b45b99e6f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027269809467877584",
"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/8861839747189349936/+/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:5046bb45fd54b810",
"user_agent:luci-scheduler"
],
"updated_ts": "1607091666236817",
"url": "https://ci.chromium.org/b/8861839747189349936",
"utcnow_ts": "1607091669251460"
}
}
[14:21:09.267] Invocation finished in 1h0m11.619047302s with status SUCCEEDED