[21:08:28.182] New invocation is queued and will start shortly
[21:08:29.383] Starting the invocation (attempt 1)
[21:08:29.423] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:08:29.425] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:08:29.425] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017999505813285872",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017999505813285872",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"gitiles_ref:refs/heads/master"
]
}
[21:08:31.103] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615928909570508",
"id": "8852569443677390272",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615928910814685",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46194",
"builder:linux-chromeos-rel",
"buildset:commit/git/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017999505813285872",
"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/8852569443677390272/+/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": "1615928910814801",
"url": "https://ci.chromium.org/b/8852569443677390272",
"utcnow_ts": "1615928911078696"
}
}
[21:08:31.103] Task URL: https://ci.chromium.org/b/8852569443677390272
[21:08:31.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:2:0) after 1m0s
[21:09:31.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:2:0)
[21:09:31.137] Timer tick, asking Buildbucket for the build status
[21:09:31.233] Build 8852569443677390272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:31.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:3:0) after 1m0s
[21:10:29.874] Received PubSub notification, asking Buildbucket for the build status
[21:10:29.933] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:31.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:3:0)
[21:10:31.245] Timer tick, asking Buildbucket for the build status
[21:10:31.317] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:31.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:5:0) after 1m0s
[21:11:31.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:5:0)
[21:11:31.424] Timer tick, asking Buildbucket for the build status
[21:11:31.517] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:31.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:6:0) after 1m0s
[21:12:31.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:6:0)
[21:12:31.574] Timer tick, asking Buildbucket for the build status
[21:12:31.640] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:31.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:7:0) after 1m0s
[21:13:31.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:7:0)
[21:13:31.700] Timer tick, asking Buildbucket for the build status
[21:13:31.908] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:31.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:8:0) after 1m0s
[21:14:31.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:8:0)
[21:14:31.938] Timer tick, asking Buildbucket for the build status
[21:14:32.068] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:32.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:9:0) after 1m0s
[21:15:32.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:9:0)
[21:15:32.086] Timer tick, asking Buildbucket for the build status
[21:15:32.175] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:32.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:10:0) after 1m0s
[21:16:32.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:10:0)
[21:16:32.156] Timer tick, asking Buildbucket for the build status
[21:16:32.402] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:32.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:11:0) after 1m0s
[21:17:32.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:11:0)
[21:17:32.443] Timer tick, asking Buildbucket for the build status
[21:17:32.543] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:32.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:12:0) after 1m0s
[21:18:32.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:12:0)
[21:18:32.716] Timer tick, asking Buildbucket for the build status
[21:18:32.814] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:32.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:13:0) after 1m0s
[21:19:32.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:13:0)
[21:19:32.788] Timer tick, asking Buildbucket for the build status
[21:19:32.923] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:32.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:14:0) after 1m0s
[21:20:32.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:14:0)
[21:20:32.987] Timer tick, asking Buildbucket for the build status
[21:20:33.210] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:33.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:15:0) after 1m0s
[21:21:33.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:15:0)
[21:21:33.245] Timer tick, asking Buildbucket for the build status
[21:21:33.512] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:33.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:16:0) after 1m0s
[21:22:33.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:16:0)
[21:22:33.508] Timer tick, asking Buildbucket for the build status
[21:22:33.580] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:33.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:17:0) after 1m0s
[21:23:33.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:17:0)
[21:23:33.604] Timer tick, asking Buildbucket for the build status
[21:23:33.968] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:33.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:18:0) after 1m0s
[21:24:33.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:18:0)
[21:24:33.980] Timer tick, asking Buildbucket for the build status
[21:24:34.264] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:34.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:19:0) after 1m0s
[21:25:34.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:19:0)
[21:25:34.263] Timer tick, asking Buildbucket for the build status
[21:25:34.563] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:34.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:20:0) after 1m0s
[21:26:34.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:20:0)
[21:26:34.613] Timer tick, asking Buildbucket for the build status
[21:26:34.682] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:34.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:21:0) after 1m0s
[21:27:34.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:21:0)
[21:27:34.670] Timer tick, asking Buildbucket for the build status
[21:27:34.738] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:34.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:22:0) after 1m0s
[21:28:34.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:22:0)
[21:28:34.778] Timer tick, asking Buildbucket for the build status
[21:28:34.845] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:34.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:23:0) after 1m0s
[21:29:34.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:23:0)
[21:29:34.928] Timer tick, asking Buildbucket for the build status
[21:29:35.033] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:35.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:24:0) after 1m0s
[21:30:35.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:24:0)
[21:30:35.003] Timer tick, asking Buildbucket for the build status
[21:30:35.125] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:35.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:25:0) after 1m0s
[21:31:35.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:25:0)
[21:31:35.176] Timer tick, asking Buildbucket for the build status
[21:31:35.337] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:35.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:26:0) after 1m0s
[21:32:35.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:26:0)
[21:32:35.383] Timer tick, asking Buildbucket for the build status
[21:32:35.441] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:35.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:27:0) after 1m0s
[21:33:35.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:27:0)
[21:33:35.491] Timer tick, asking Buildbucket for the build status
[21:33:35.846] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:35.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:28:0) after 1m0s
[21:34:35.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:28:0)
[21:34:35.903] Timer tick, asking Buildbucket for the build status
[21:34:36.181] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:36.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:29:0) after 1m0s
[21:35:36.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:29:0)
[21:35:36.229] Timer tick, asking Buildbucket for the build status
[21:35:36.414] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:36.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:30:0) after 1m0s
[21:36:36.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:30:0)
[21:36:36.447] Timer tick, asking Buildbucket for the build status
[21:36:37.067] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:37.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:31:0) after 1m0s
[21:37:37.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:31:0)
[21:37:37.237] Timer tick, asking Buildbucket for the build status
[21:37:37.324] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:37.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:32:0) after 1m0s
[21:38:37.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:32:0)
[21:38:37.462] Timer tick, asking Buildbucket for the build status
[21:38:37.552] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:37.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:33:0) after 1m0s
[21:39:37.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:33:0)
[21:39:37.648] Timer tick, asking Buildbucket for the build status
[21:39:37.710] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:37.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:34:0) after 1m0s
[21:40:37.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:34:0)
[21:40:37.753] Timer tick, asking Buildbucket for the build status
[21:40:37.865] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:37.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:35:0) after 1m0s
[21:41:37.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:35:0)
[21:41:37.890] Timer tick, asking Buildbucket for the build status
[21:41:38.017] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:38.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:36:0) after 1m0s
[21:42:38.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:36:0)
[21:42:38.085] Timer tick, asking Buildbucket for the build status
[21:42:38.177] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:38.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:37:0) after 1m0s
[21:43:38.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:37:0)
[21:43:38.219] Timer tick, asking Buildbucket for the build status
[21:43:38.360] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:38.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:38:0) after 1m0s
[21:44:38.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:38:0)
[21:44:38.385] Timer tick, asking Buildbucket for the build status
[21:44:38.464] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:38.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:39:0) after 1m0s
[21:45:38.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:39:0)
[21:45:38.461] Timer tick, asking Buildbucket for the build status
[21:45:38.556] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:38.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:40:0) after 1m0s
[21:46:38.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:40:0)
[21:46:38.648] Timer tick, asking Buildbucket for the build status
[21:46:38.915] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:38.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:41:0) after 1m0s
[21:47:38.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:41:0)
[21:47:38.937] Timer tick, asking Buildbucket for the build status
[21:47:39.061] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:39.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:42:0) after 1m0s
[21:48:39.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:42:0)
[21:48:39.099] Timer tick, asking Buildbucket for the build status
[21:48:39.173] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:39.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:43:0) after 1m0s
[21:49:39.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:43:0)
[21:49:39.211] Timer tick, asking Buildbucket for the build status
[21:49:39.289] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:39.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:44:0) after 1m0s
[21:50:39.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:44:0)
[21:50:39.311] Timer tick, asking Buildbucket for the build status
[21:50:39.387] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:39.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:45:0) after 1m0s
[21:51:39.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:45:0)
[21:51:39.559] Timer tick, asking Buildbucket for the build status
[21:51:39.646] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:39.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:46:0) after 1m0s
[21:52:39.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:46:0)
[21:52:39.661] Timer tick, asking Buildbucket for the build status
[21:52:39.733] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:39.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:47:0) after 1m0s
[21:53:39.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:47:0)
[21:53:39.823] Timer tick, asking Buildbucket for the build status
[21:53:39.907] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:39.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:48:0) after 1m0s
[21:54:39.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:48:0)
[21:54:39.974] Timer tick, asking Buildbucket for the build status
[21:54:40.046] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:40.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:49:0) after 1m0s
[21:55:40.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:49:0)
[21:55:40.057] Timer tick, asking Buildbucket for the build status
[21:55:40.132] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:40.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:50:0) after 1m0s
[21:56:40.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:50:0)
[21:56:40.211] Timer tick, asking Buildbucket for the build status
[21:56:40.308] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:40.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:51:0) after 1m0s
[21:57:40.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:51:0)
[21:57:40.392] Timer tick, asking Buildbucket for the build status
[21:57:40.468] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:40.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:52:0) after 1m0s
[21:58:41.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:52:0)
[21:58:41.196] Timer tick, asking Buildbucket for the build status
[21:58:41.280] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:41.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:53:0) after 1m0s
[21:59:41.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:53:0)
[21:59:41.332] Timer tick, asking Buildbucket for the build status
[21:59:41.466] Build 8852569443677390272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:41.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017999505813285872:54:0) after 1m0s
[22:00:06.236] Received PubSub notification, asking Buildbucket for the build status
[22:00:07.783] Build 8852569443677390272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:00:07.783] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615932005292261",
"created_by": "project:chromium",
"created_ts": "1615928909570508",
"id": "8852569443677390272",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\"}}",
"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\": \"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\", \"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-166-3f40\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46194, \"got_angle_revision\": \"79e39478028c56b4be4e4549f8c9e53381ae48ce\", \"got_dawn_revision\": \"54586e61210cfdfc9835f81b57c794f4ac19356b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\", \"got_revision_cp\": \"refs/heads/master@{#863455}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"f53ac97298868f535ef84b516d80ebb6d41c67eb\", \"got_v8_revision_cp\": \"refs/heads/9.1.135@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"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\": \"7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae\", \"swarm_hashes_refs/heads/master(at){#863455}_without_patch\": {\"absl_hardening_tests\": \"09a75f857b8735ea1ead1e094b98d0fe441d7c96\", \"accessibility_unittests\": \"365c3a8abb898b3cb8c1fbd3ac10e73ec2798fb6\", \"angle_unittests\": \"1b5a912c1892464e19a7c0944deaf09e9b3b8d71\", \"app_list_unittests\": \"4ec80c0bf58db3a33a7b6479bce2d430a32d443b\", \"app_shell_unittests\": \"ee97fa9afe4d14e4c0b54f26b9a702d6edfe1d8e\", \"ash_unittests\": \"805a5b347938f6edc06086d946eda2a02369cb95\", \"aura_unittests\": \"6063ea1b1734cbd99a702d364faf664cc61ef941\", \"base_unittests\": \"c3a804cb3e037e96878644fd550e90a61a54c6a3\", \"base_util_unittests\": \"a6d8a2f75a9dbc361df44b882d3e1c256e7614c1\", \"blink_common_unittests\": \"0a78e36825569fbe3a140602e4507f77daec7133\", \"blink_fuzzer_unittests\": \"94e7c876c15b601ce02ac15b0ecafd77ac7d2658\", \"blink_heap_unittests\": \"25c2e8e75392ac99513fdddd6282fad67aa7871b\", \"blink_platform_unittests\": \"6126a76648cde1ec4ffe052f6d47f8df2bdcc8ca\", \"blink_unittests\": \"b08f1526c54a09dc78363ae4ca2f03f8e2479495\", \"boringssl_crypto_tests\": \"8e6103e98e37508c7e25331a557b96b71fe2f970\", \"boringssl_ssl_tests\": \"6633fcfaea23a70d368f4688429153a4b152d6e0\", \"browser_tests\": \"61d665355156d08a7b689a6eabf04a100ccdbb13\", \"capture_unittests\": \"155eb82367c245166c9e8777c6249b871c45b6da\", \"cast_unittests\": \"7a7f0ca90f25998e138eab941bf67130a51e744b\", \"cc_unittests\": \"103a4142cf4a942a94084e518300438944c8eb92\", \"chrome_app_unittests\": \"39712949c0a78e04b3426947c0c6c2204fab35c6\", \"chromedriver_unittests\": \"c7dd0354b51a862029bbd197b6f81acc9649e122\", \"chromeos_components_unittests\": \"e832f21adf9019065eee6ad5d628364fbeccc6b8\", \"chromeos_unittests\": \"156a6116c3f004f79271f6e9d53c624f73fb2b58\", \"color_unittests\": \"532f9aef060d330e6a7d2f908a2193df2dd4d76a\", \"components_browsertests\": \"2c2d4899404ca9ff2ce4312428dbbaaf60cc91ea\", \"components_unittests\": \"338c29f301781bb57a0c7441dd00ed8388631b93\", \"compositor_unittests\": \"a9bada80ded490ec95d1b02149cc824c3b030aa6\", \"content_browsertests\": \"6f983e2e009dac68fdfd2c0c2e026ab7fa86f8eb\", \"content_unittests\": \"412ea4ee4a52eac32c43d9e0864534d605f545bf\", \"crashpad_tests\": \"7d0d3f35558f4e3d6a450f17a604b73f7969e781\", \"crypto_unittests\": \"9a6585219333c000eeeac2f00980d2d38383c1d6\", \"dbus_unittests\": \"617457838f0c26d698c0f3c6be80678df6d92d24\", \"device_unittests\": \"43f3f66a7c88049c956d76bc543c3c582b872d69\", \"display_unittests\": \"46ddc0b549eb851445e50d4287c639d26adb6e3f\", \"events_unittests\": \"8145cc113fbc15fa3e9cc998670175fe03de77de\", \"exo_unittests\": \"9f1ca9dbab81ad5f45fb70ffeeb7a44fa03db1f7\", \"extensions_browsertests\": \"b6c2ab877212ad5d7388fcc0fd39c8f5be6ce1c3\", \"extensions_unittests\": \"bdec39a9657b3106ac5293e1f6c85acda694776a\", \"filesystem_service_unittests\": \"7fe4df8dbc8a5e9751da74b8fb82059d62e01a56\", \"gcm_unit_tests\": \"1bb078cec163047db6062d2bdcbc248c92149941\", \"gfx_unittests\": \"a2fea11e0ff264d85dffb55acfd1a7f95ab3ce25\", \"gin_unittests\": \"c0425b8d959b4a54c7d2c6777e7b0c4cbc0081f3\", \"gl_unittests_ozone\": \"f4b36c3425b736fd6f060d4f33ba9f1b427f70e2\", \"google_apis_unittests\": \"afba2a9f2beb5f73c09a1e32aed5b387ba4dd841\", \"gpu_unittests\": \"ab8547cbba1a97dee150dc17686e1121cc14a0a8\", \"gwp_asan_unittests\": \"80c6107d4a8356b36ed0a3643d2bec6567711e68\", \"interactive_ui_tests\": \"a615c677ef70d630a9c493799491b4f95bd665d2\", \"ipc_tests\": \"af2e917b98bc02ddd9efbb190d0feb20d1369d77\", \"jingle_unittests\": \"81f0f87cd6da1e4d8c98a05c28ca2b19f4bc156c\", \"keyboard_unittests\": \"639b8114aa6541c69776a1f81212b40224f3a9f9\", \"latency_unittests\": \"6d5700b0c57455b80658b938d5fa15ee4be0185b\", \"libjingle_xmpp_unittests\": \"1bf44bd3c0f02b249f74df30cc4749bd0a465461\", \"liburlpattern_unittests\": \"e03cedeb0556caf571bc126cde5ddae7401926a6\", \"media_blink_unittests\": \"cf53c586da744f0513e75d7226bb8580e12bdc43\", \"media_unittests\": \"9e8af21b6ef36e1fce58260e2b24a3a2db5396a1\", \"message_center_unittests\": \"ff3529245198e2f6a90e71f3231e4f402ae14690\", \"midi_unittests\": \"d53c09b55eb748a765a743159c8f3a96ce04a06b\", \"mojo_core_unittests\": \"0e1c2ebb2d7362855ab98e825e288c09a0684310\", \"mojo_unittests\": \"f5239206fb1544522bf22d577dd9ebcc3d6b4167\", \"nacl_helper_nonsfi_unittests\": \"36364424047c4c83da458f5e29b89504d2c35209\", \"nacl_loader_unittests\": \"6f611d4e7339a31d01d258dd14da1f7dbebbc847\", \"native_theme_unittests\": \"f8a6df65d19a2ec5423fdf3eff1ae273551ceb1e\", \"net_unittests\": \"bf46f07c3676a67443794b16e12c6dafa49d204a\", \"ozone_gl_unittests\": \"50037f2409212653242a480d30cd01037e9844ea\", \"ozone_unittests\": \"cbed9c5c008e88cb4ab73c618ab432eef67c9536\", \"ozone_x11_unittests\": \"a182ca1513473007400ddd9cabb23afdeb667ddd\", \"pdf_unittests\": \"c888b9e417b8e29df26eb5a138abbceedc82a1f3\", \"perfetto_unittests\": \"e5bdf92ac8335a9942238310e6e48e769f16770a\", \"ppapi_unittests\": \"b96d5d386fce47ebb45723c17298f396ee94e354\", \"printing_unittests\": \"751f372852edb23b6fdddb0b67c30cae1ad6cc16\", \"remoting_unittests\": \"3cae9f077815895e171029b970bd38e5e200b455\", \"sandbox_linux_unittests\": \"7cdabaf719dd09883c19c13f0e047fa5f8cda040\", \"service_manager_unittests\": \"95ea0e3ec448e87ad18d179ba6f15f3a9204dc70\", \"services_unittests\": \"1bcf420d20aab05020a1cb7f8096f10cecaebaa0\", \"shell_dialogs_unittests\": \"7ee9ead2ab2e4e0c3ae810f76a981be326879535\", \"shell_encryption_unittests\": \"bd949448cce9b2539bf06e52e8cfac58facf480c\", \"skia_unittests\": \"69d2941de25017ba0d8ce74e9cb8f325254d4d54\", \"snapshot_unittests\": \"85640e694aa58f6ac7bcc3409f5ea8975cecbaf3\", \"sql_unittests\": \"d984433c79e2b4540254e1193c19928d5dcb6f9c\", \"storage_unittests\": \"36d4726429ea6f99bf84f90d2dde568ad9fc4f70\", \"sync_integration_tests\": \"ef695fbaa8b1ddaac58d056a57d8f5261496f256\", \"traffic_annotation_auditor_unittests\": \"3b0c5ebd6cd4b03c4a8911bcd27a98b9b79b88d4\", \"ui_base_unittests\": \"b5f502af243c425498c686e1fe79e61d1452be90\", \"ui_chromeos_unittests\": \"e65cfd125e6cea18cad5658e7da8619998972afb\", \"ui_touch_selection_unittests\": \"3567bf6d85991b2f9b7ea489ce6923c3a2d186f0\", \"unit_tests\": \"36c85d1a7b193a8c005eb967b673830fa33975b7\", \"url_unittests\": \"628ae595fda6611ec88f42ceb69423f0f8fa7a84\", \"usage_time_limit_unittests\": \"d63daf9bb0765db039a347fabf2bf6e98ee5bf3b\", \"views_unittests\": \"42a6aaa41c150f90f0b6a7e2a38d9297e9342228\", \"viz_unittests\": \"9a3b2bd5234a246b67d0e2701b077b25235d0413\", \"wayland_client_perftests\": \"dacdb07d5fa9fd25a3b1d578433435256c6c7937\", \"wayland_client_tests\": \"c375ed8622b726bf566cf21857f889615a870e1b\", \"wm_unittests\": \"51a219373c679a8ae23a986fba955ad2ba74c710\", \"wtf_unittests\": \"23c789e3358914b98f9edb5512cd443819780a35\", \"zlib_unittests\": \"d4b8749b60f3e27002bc5806271d940afde0bf5f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_304607b978b8f4bcd63bac0f543c3a69f6b4c7cf202dcd2d3c5b8d4a70be201e_v2\", \"builder_61cf7edf32cfa6f409296179bf0eb529cec28454dd3732e2fae2e14176388844_v2\", \"builder_6440ef9dedd0dc3b34dcb22ab34f3415d4cc49eb5cc8c1aeab1a9757364e9da5_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_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-166-3f40\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615929028938541",
"status": "COMPLETED",
"status_changed_ts": "1615932005661884",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46194",
"builder:linux-chromeos-rel",
"buildset:commit/git/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ed90b6d1e097241231d66fb2ab6b7e2d5e49dae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017999505813285872",
"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/8852569443677390272/+/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:5255afee24dd8a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615932005678161",
"url": "https://ci.chromium.org/b/8852569443677390272",
"utcnow_ts": "1615932006383346"
}
}
[22:00:07.783] Invocation finished in 51m39.633865684s with status SUCCEEDED