[15:39:02.738] New invocation is queued and will start shortly
[15:39:04.780] Starting the invocation (attempt 1)
[15:39:04.815] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:39:04.815] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:39:04.815] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021734706988746544",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fcba46172a5b0391188215e89f91146ef2dd2364\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021734706988746544",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/fcba46172a5b0391188215e89f91146ef2dd2364",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcba46172a5b0391188215e89f91146ef2dd2364",
"gitiles_ref:refs/heads/master"
]
}
[15:39:05.608] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612366744970854",
"id": "8856304643985040208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fcba46172a5b0391188215e89f91146ef2dd2364\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612366745417709",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45115",
"builder:linux-chromeos-rel",
"buildset:commit/git/fcba46172a5b0391188215e89f91146ef2dd2364",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcba46172a5b0391188215e89f91146ef2dd2364",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021734706988746544",
"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/8856304643985040208/+/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": "1612366745419069",
"url": "https://ci.chromium.org/b/8856304643985040208",
"utcnow_ts": "1612366745593681"
}
}
[15:39:05.608] Task URL: https://ci.chromium.org/b/8856304643985040208
[15:39:05.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:2:0) after 1m0s
[15:39:19.655] Received PubSub notification, asking Buildbucket for the build status
[15:39:19.733] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:05.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:2:0)
[15:40:05.642] Timer tick, asking Buildbucket for the build status
[15:40:05.801] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:05.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:4:0) after 1m0s
[15:41:05.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:4:0)
[15:41:05.905] Timer tick, asking Buildbucket for the build status
[15:41:06.043] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:06.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:5:0) after 1m0s
[15:42:06.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:5:0)
[15:42:06.063] Timer tick, asking Buildbucket for the build status
[15:42:06.133] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:06.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:6:0) after 1m0s
[15:43:06.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:6:0)
[15:43:06.231] Timer tick, asking Buildbucket for the build status
[15:43:06.309] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:06.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:7:0) after 1m0s
[15:44:06.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:7:0)
[15:44:06.356] Timer tick, asking Buildbucket for the build status
[15:44:06.482] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:06.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:8:0) after 1m0s
[15:45:06.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:8:0)
[15:45:06.608] Timer tick, asking Buildbucket for the build status
[15:45:06.832] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:06.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:9:0) after 1m0s
[15:46:06.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:9:0)
[15:46:06.839] Timer tick, asking Buildbucket for the build status
[15:46:07.013] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:07.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:10:0) after 1m0s
[15:47:07.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:10:0)
[15:47:07.093] Timer tick, asking Buildbucket for the build status
[15:47:07.200] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:07.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:11:0) after 1m0s
[15:48:07.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:11:0)
[15:48:07.231] Timer tick, asking Buildbucket for the build status
[15:48:07.410] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:07.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:12:0) after 1m0s
[15:49:07.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:12:0)
[15:49:07.472] Timer tick, asking Buildbucket for the build status
[15:49:07.657] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:07.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:13:0) after 1m0s
[15:50:07.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:13:0)
[15:50:07.691] Timer tick, asking Buildbucket for the build status
[15:50:07.762] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:07.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:14:0) after 1m0s
[15:51:07.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:14:0)
[15:51:07.784] Timer tick, asking Buildbucket for the build status
[15:51:07.927] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:07.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:15:0) after 1m0s
[15:52:07.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:15:0)
[15:52:07.945] Timer tick, asking Buildbucket for the build status
[15:52:08.007] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:08.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:16:0) after 1m0s
[15:53:08.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:16:0)
[15:53:08.037] Timer tick, asking Buildbucket for the build status
[15:53:08.305] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:08.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:17:0) after 1m0s
[15:54:08.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:17:0)
[15:54:08.330] Timer tick, asking Buildbucket for the build status
[15:54:08.735] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:08.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:18:0) after 1m0s
[15:55:08.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:18:0)
[15:55:08.758] Timer tick, asking Buildbucket for the build status
[15:55:08.846] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:08.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:19:0) after 1m0s
[15:56:08.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:19:0)
[15:56:08.833] Timer tick, asking Buildbucket for the build status
[15:56:09.112] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:09.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:20:0) after 1m0s
[15:57:09.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:20:0)
[15:57:09.207] Timer tick, asking Buildbucket for the build status
[15:57:09.264] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:09.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:21:0) after 1m0s
[15:58:09.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:21:0)
[15:58:09.307] Timer tick, asking Buildbucket for the build status
[15:58:09.369] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:09.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:22:0) after 1m0s
[15:59:09.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:22:0)
[15:59:09.366] Timer tick, asking Buildbucket for the build status
[15:59:09.481] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:09.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:23:0) after 1m0s
[16:00:09.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:23:0)
[16:00:09.543] Timer tick, asking Buildbucket for the build status
[16:00:09.667] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:09.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:24:0) after 1m0s
[16:01:09.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:24:0)
[16:01:09.673] Timer tick, asking Buildbucket for the build status
[16:01:09.847] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:25:0) after 1m0s
[16:02:09.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:25:0)
[16:02:09.896] Timer tick, asking Buildbucket for the build status
[16:02:10.052] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:10.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:26:0) after 1m0s
[16:03:10.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:26:0)
[16:03:10.160] Timer tick, asking Buildbucket for the build status
[16:03:10.237] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:27:0) after 1m0s
[16:04:10.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:27:0)
[16:04:10.248] Timer tick, asking Buildbucket for the build status
[16:04:10.375] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:10.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:28:0) after 1m0s
[16:05:10.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:28:0)
[16:05:10.451] Timer tick, asking Buildbucket for the build status
[16:05:10.593] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:10.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:29:0) after 1m0s
[16:06:10.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:29:0)
[16:06:10.659] Timer tick, asking Buildbucket for the build status
[16:06:10.753] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:10.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:30:0) after 1m0s
[16:07:10.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:30:0)
[16:07:10.828] Timer tick, asking Buildbucket for the build status
[16:07:10.917] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:10.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:31:0) after 1m0s
[16:08:10.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:31:0)
[16:08:10.977] Timer tick, asking Buildbucket for the build status
[16:08:11.125] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:11.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:32:0) after 1m0s
[16:09:11.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:32:0)
[16:09:11.212] Timer tick, asking Buildbucket for the build status
[16:09:11.329] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:11.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:33:0) after 1m0s
[16:10:11.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:33:0)
[16:10:11.429] Timer tick, asking Buildbucket for the build status
[16:10:11.789] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:11.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:34:0) after 1m0s
[16:11:11.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:34:0)
[16:11:11.970] Timer tick, asking Buildbucket for the build status
[16:11:12.177] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:12.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:35:0) after 1m0s
[16:12:12.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:35:0)
[16:12:12.362] Timer tick, asking Buildbucket for the build status
[16:12:12.424] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:12.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:36:0) after 1m0s
[16:13:12.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:36:0)
[16:13:12.533] Timer tick, asking Buildbucket for the build status
[16:13:12.626] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:12.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:37:0) after 1m0s
[16:14:12.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:37:0)
[16:14:12.732] Timer tick, asking Buildbucket for the build status
[16:14:12.866] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:12.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:38:0) after 1m0s
[16:15:12.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:38:0)
[16:15:12.990] Timer tick, asking Buildbucket for the build status
[16:15:13.126] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:13.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:39:0) after 1m0s
[16:16:13.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:39:0)
[16:16:13.273] Timer tick, asking Buildbucket for the build status
[16:16:13.330] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:40:0) after 1m0s
[16:17:13.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:40:0)
[16:17:13.372] Timer tick, asking Buildbucket for the build status
[16:17:13.445] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:13.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:41:0) after 1m0s
[16:18:13.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:41:0)
[16:18:13.461] Timer tick, asking Buildbucket for the build status
[16:18:13.536] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:13.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:42:0) after 1m0s
[16:19:13.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:42:0)
[16:19:13.652] Timer tick, asking Buildbucket for the build status
[16:19:13.780] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:13.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:43:0) after 1m0s
[16:20:13.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:43:0)
[16:20:13.794] Timer tick, asking Buildbucket for the build status
[16:20:13.894] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:44:0) after 1m0s
[16:21:13.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:44:0)
[16:21:13.916] Timer tick, asking Buildbucket for the build status
[16:21:14.037] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:14.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:45:0) after 1m0s
[16:22:14.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:45:0)
[16:22:14.112] Timer tick, asking Buildbucket for the build status
[16:22:14.223] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:14.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:46:0) after 1m0s
[16:23:14.279] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:46:0)
[16:23:14.279] Timer tick, asking Buildbucket for the build status
[16:23:14.524] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:14.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:47:0) after 1m0s
[16:24:14.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:47:0)
[16:24:14.587] Timer tick, asking Buildbucket for the build status
[16:24:14.833] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:14.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:48:0) after 1m0s
[16:25:14.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:48:0)
[16:25:14.989] Timer tick, asking Buildbucket for the build status
[16:25:15.150] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:15.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:49:0) after 1m0s
[16:26:15.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:49:0)
[16:26:15.228] Timer tick, asking Buildbucket for the build status
[16:26:15.617] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:15.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:50:0) after 1m0s
[16:27:15.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:50:0)
[16:27:15.763] Timer tick, asking Buildbucket for the build status
[16:27:15.870] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:15.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:51:0) after 1m0s
[16:28:15.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:51:0)
[16:28:15.961] Timer tick, asking Buildbucket for the build status
[16:28:16.178] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:16.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:52:0) after 1m0s
[16:29:16.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:52:0)
[16:29:16.212] Timer tick, asking Buildbucket for the build status
[16:29:16.330] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:16.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:53:0) after 1m0s
[16:30:16.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:53:0)
[16:30:16.648] Timer tick, asking Buildbucket for the build status
[16:30:17.024] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:17.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:54:0) after 1m0s
[16:31:17.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:54:0)
[16:31:17.085] Timer tick, asking Buildbucket for the build status
[16:31:17.286] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:17.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:55:0) after 1m0s
[16:32:17.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:55:0)
[16:32:17.327] Timer tick, asking Buildbucket for the build status
[16:32:17.521] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:17.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:56:0) after 1m0s
[16:33:17.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:56:0)
[16:33:17.650] Timer tick, asking Buildbucket for the build status
[16:33:17.906] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:17.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:57:0) after 1m0s
[16:34:17.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:57:0)
[16:34:17.925] Timer tick, asking Buildbucket for the build status
[16:34:18.045] Build 8856304643985040208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:18.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021734706988746544:58:0) after 1m0s
[16:35:11.387] Received PubSub notification, asking Buildbucket for the build status
[16:35:11.468] Build 8856304643985040208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:35:11.468] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612370109749059",
"created_by": "project:chromium",
"created_ts": "1612366744970854",
"id": "8856304643985040208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fcba46172a5b0391188215e89f91146ef2dd2364\"}}",
"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\": \"fcba46172a5b0391188215e89f91146ef2dd2364\", \"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-104-3z9c\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45115, \"got_angle_revision\": \"2e54a1e7fc49e1c1c659d7be659b832954112106\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"fcba46172a5b0391188215e89f91146ef2dd2364\", \"got_revision_cp\": \"refs/heads/master@{#850118}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"fcba46172a5b0391188215e89f91146ef2dd2364\", \"swarm_hashes_refs/heads/master(at){#850118}_without_patch\": {\"absl_hardening_tests\": \"1d833546db695ca1cc3aa0e57a311bcf4fb63f79\", \"accessibility_unittests\": \"0d38cf32ceb8d0b5df2082e78fe6e8ec7e8531e3\", \"angle_unittests\": \"777626e80961ce15108f55bc4a2b4212b83f5ec7\", \"app_list_unittests\": \"0ac8783ef231fec694cfb700b1a76c86557882d8\", \"app_shell_unittests\": \"8c3c47a9813b9a0a3ffe79a3dbdd2cca9b340e0d\", \"ash_unittests\": \"db491fdee2757a227bee03aaf10a2e010e3fc823\", \"aura_unittests\": \"98168fc3d0c4d033ade7e60e4eae29cbaf296b79\", \"base_unittests\": \"a03663fd5c815feb48cd93a9fcd059b040a31f3b\", \"base_util_unittests\": \"c6127ec5425ca01c87981abc7bc648a10d59fded\", \"blink_common_unittests\": \"c6e372199a76e03adac413094856ac6ccb71807c\", \"blink_fuzzer_unittests\": \"484da1d72f96f05ec398757ebf176096ba2ff4a3\", \"blink_heap_unittests\": \"b8c92ae4bfea15e9e28153cd3b71b2386e81a97f\", \"blink_platform_unittests\": \"250116f800dedebef88e3818a79f6e34548f635f\", \"blink_unittests\": \"e92e022bef06018dcef6ad467976f390ac2817e0\", \"boringssl_crypto_tests\": \"05d7fcfb0b8ebb5d583d405ad212d7814cf41c4f\", \"boringssl_ssl_tests\": \"4e4707e9971c1879d2958c02d9605f3a29163a0d\", \"browser_tests\": \"4d6c4f3e248020a45e6199b75207c0d72ca8202a\", \"capture_unittests\": \"2fe1a019abd06a518347b228451eb35cdeb4074f\", \"cast_unittests\": \"bb72ec1948bf8ead70eb93ea1dae7acd24862b7e\", \"cc_unittests\": \"cd73aa63dae4d0b100d9facfa6942aa2a849b532\", \"chrome_app_unittests\": \"6bf7e9cfcfc950c9deb8263911d796436575e69a\", \"chromedriver_unittests\": \"d1dfe8a3da9f89a406d9d070f661a33ec8bbd85c\", \"chromeos_components_unittests\": \"cdb0eb0a74699ef153efc0cf29342e9ed4efd48b\", \"chromeos_unittests\": \"0c12922178414fa1f1d740a6f664f05d471a6279\", \"components_browsertests\": \"8eb3163400336ec4df2a691c542527da7964dd5a\", \"components_unittests\": \"b890a5c538bed781d9994aa7f0dea00570936437\", \"compositor_unittests\": \"cbfbee137afb52eb408f1b5ffd2bd687146b84ca\", \"content_browsertests\": \"e63a9861f961fc1e4f1becc1a1d44701952fa28e\", \"content_unittests\": \"4eeab6e2f70c75a0c3188a0efcf2681062a933f5\", \"crashpad_tests\": \"4a55fbcbd283a0e7bc19cbf8e73277dc90902fae\", \"crypto_unittests\": \"7c80d9201b4fc6bf543b2a6cd7d147c4b5b2147a\", \"dbus_unittests\": \"fe8d1867cb64122d0f0dcd4ee1859443cd4b15ec\", \"device_unittests\": \"2c36ba950075178a0f776c70342ec15e9fd2f02e\", \"display_unittests\": \"21d31cc0a2f228dd9db95b15da31b3a61f288ec1\", \"events_unittests\": \"97f55a98aef31b2b4102e97a1c89473ca32537bf\", \"exo_unittests\": \"cda2a2573c4d2785a633113cf95a786dfdd2c7b3\", \"extensions_browsertests\": \"b668c86b73b64ba162501c5e5639c9992576c826\", \"extensions_unittests\": \"3b3799ddf29ed9c3e2ada482c397eb8fc1b5b13e\", \"filesystem_service_unittests\": \"5fa6243f3d00a753f0be02f14160397783cefd1c\", \"gcm_unit_tests\": \"86a879633f6df236ef501c38479e8d44eab71a69\", \"gfx_unittests\": \"c17b6e2448644389e2e320e1f0588b32047f400b\", \"gin_unittests\": \"97e48cd215ebc613c58ebae6f3ac852368b0e8a8\", \"gl_unittests_ozone\": \"54915bc6ea4ff6677e65ba73d97de6df74611fb3\", \"google_apis_unittests\": \"276d8a95b5b5e7912305a0b6c024abca9e2ec25c\", \"gpu_unittests\": \"6eb76f27ac0ba00add449a945f24e510c9c0b203\", \"gwp_asan_unittests\": \"aa821e38119fea8d44d1ec871519ada3acb6a51c\", \"interactive_ui_tests\": \"2a83f96f4ab4f04b10e8db459a1b108f3bd725bf\", \"ipc_tests\": \"2ebc3063bd602c7f01e8f3c75ac4a479305eaf0b\", \"jingle_unittests\": \"6e243c61bb9d24c7104dc71964cc50f79c798d35\", \"keyboard_unittests\": \"c8f6fd5660532ed4ddf0f3a951625fe91983976b\", \"latency_unittests\": \"2d163b3fcccfa03dcf7dfa39ab3f1c1ae49a2445\", \"libjingle_xmpp_unittests\": \"e4ef3fa618b42472f393aa09f00e0f4f45394b64\", \"liburlpattern_unittests\": \"691ba43dab3e29c9d1789082ac9e6e7d8fa3ec6d\", \"media_blink_unittests\": \"a8e8d752eb2e4f6731b31fd9c7717d6f82bc8f76\", \"media_unittests\": \"f43855a9b4c29eed82c497f287007c744eb3330e\", \"message_center_unittests\": \"a319fc515a961316dffde00e7080d5f82fff0b4c\", \"midi_unittests\": \"9887f26e036fde074f874eeee6a4c3a818143d26\", \"mojo_core_unittests\": \"89f51d6b7ff2f48f34cffbbbc0ea3a42d638ce9e\", \"mojo_unittests\": \"10c93bbb4e4a932798825bd80cc887aa3d7f100a\", \"nacl_helper_nonsfi_unittests\": \"027226c2ca62aa70ae9444f20646e6150c23cefc\", \"nacl_loader_unittests\": \"7469d824a60ba5f5a9eaced6ced56ae5620f8ce0\", \"native_theme_unittests\": \"52d6034854292820c206502b6a78a34f3167aab3\", \"net_unittests\": \"374ca7eb0efab44b649cbda506d41d9e071e3ad2\", \"ozone_gl_unittests\": \"9d8c17bd57286fbf721f7a38c1d676314067e378\", \"ozone_unittests\": \"acb7c2d1aa82f5b85c26489a8ab46fdea14fcb01\", \"ozone_x11_unittests\": \"a5e95566f95d22b2f63385731c430ad6f5b6d74c\", \"pdf_unittests\": \"87cb4068f4dedc2219deaf11c67f738e8b757872\", \"perfetto_unittests\": \"69f104dda9112d8a7c193cade9a1d93b500e6059\", \"ppapi_unittests\": \"ac979300503589a0e0aa21ae1a9273c2124f8fbb\", \"printing_unittests\": \"82a9e77295071582171403c679bb931315bdabe8\", \"remoting_unittests\": \"4093c4c6c1078ba503b4622cc973e80b9f682a9e\", \"sandbox_linux_unittests\": \"b56a1e2cddcb9f1ea06199e7a01d38c3b6f8ead2\", \"service_manager_unittests\": \"d05cbb4193c64adbc1bbe8ba1e3263f76a93c510\", \"services_unittests\": \"0971fa7eb67714449bec8374621ece7842810ff3\", \"shell_dialogs_unittests\": \"b639c44a1d0b528883ef763d57d3d0e46bc2ffb6\", \"shell_encryption_unittests\": \"db3a39476bdfa4bc7af22c2268ed3300b386cf5c\", \"skia_unittests\": \"b6be51e9185a131116dd9f8ba8b457ac8027fe60\", \"snapshot_unittests\": \"eaede15338577c8ea748d46ba9cd269ebda82f20\", \"sql_unittests\": \"3703c02951adc454947e3eb3c1bad2add9f8af99\", \"storage_unittests\": \"fde03cd41efdf1b4462f43e17f0fc91a2cb59d0f\", \"sync_integration_tests\": \"6f931aa691f2724ee0cab9df6ca974e484b09daf\", \"traffic_annotation_auditor_unittests\": \"b3431b7b0013737b0b3d1fff0e53767082ca7ea8\", \"ui_base_unittests\": \"8787101c8026408e0ad19535a2689e825de04c35\", \"ui_chromeos_unittests\": \"cb6de76dca24232daeccdc3a36724ac9eaa0350a\", \"ui_touch_selection_unittests\": \"50d55328a6a933f9716dc70e5b71ddfbe5b27503\", \"unit_tests\": \"2187ee132afe6f20d49c8ef0c8d9d4723f485ac5\", \"url_unittests\": \"c66006279a5fc6fb72c758046665b321ba3f7f88\", \"usage_time_limit_unittests\": \"047cbffd055128f83e032f5251332bc676e64756\", \"views_unittests\": \"dd40ecf192d12294a4fbb1cfa7d751aa35377170\", \"viz_unittests\": \"e37d40d261c4a1da9470e0e65618f6523df7f4f1\", \"wayland_client_perftests\": \"30fd53ff14f0fb8536fac7f48664489463399343\", \"wm_unittests\": \"728e57cd4a2a21502c24b989be9b26a7c131ef38\", \"wtf_unittests\": \"cd4cd03a71457397810bc8aa8925f73df54d3ddf\", \"zlib_unittests\": \"fedd3473f3d217da1e298b1854ff1c4f735cf62b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0111b226b6a2e1a66cbbf5d93fde4367495bc99d98428795ba3e931083c57f27_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_678d8b556ed6d2ea4aa43b80debb7b547a1d6d4433ddff28e80ab170912c760d_v2\", \"builder_7038f72a8497d99b8274809a89493d066575b874ae7ab7a985f73ba6942472f2_v2\", \"builder_7ddc1ece4c938056809d45345930c5d8ea6799936d2255389af4f16219f397ac_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_ce300ca2149b7ede2a11dc5f29b170d3e5c2bdd2b201177604ba7c6bb221adeb_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-104-3z9c\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612366758532373",
"status": "COMPLETED",
"status_changed_ts": "1612370110136971",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45115",
"builder:linux-chromeos-rel",
"buildset:commit/git/fcba46172a5b0391188215e89f91146ef2dd2364",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcba46172a5b0391188215e89f91146ef2dd2364",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021734706988746544",
"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/8856304643985040208/+/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:51815d98846ab910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612370110151178",
"url": "https://ci.chromium.org/b/8856304643985040208",
"utcnow_ts": "1612370111458307"
}
}
[16:35:11.468] Invocation finished in 56m8.746578615s with status SUCCEEDED