[04:52:38.520] New invocation is queued and will start shortly
[04:52:39.836] Starting the invocation (attempt 1)
[04:52:39.870] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:52:39.871] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:52:39.871] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026305223508049632",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3909caf2d715f4efd5556ea87186bba9d7535284\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9026305223508049632",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3909caf2d715f4efd5556ea87186bba9d7535284",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3909caf2d715f4efd5556ea87186bba9d7535284",
"gitiles_ref:refs/heads/master"
]
}
[04:52:40.601] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608007959964748",
"id": "8860875161330573088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3909caf2d715f4efd5556ea87186bba9d7535284\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608007960416916",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43726",
"builder:linux-chromeos-rel",
"buildset:commit/git/3909caf2d715f4efd5556ea87186bba9d7535284",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3909caf2d715f4efd5556ea87186bba9d7535284",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026305223508049632",
"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/8860875161330573088/+/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": "1608007960417027",
"url": "https://ci.chromium.org/b/8860875161330573088",
"utcnow_ts": "1608007960593149"
}
}
[04:52:40.601] Task URL: https://ci.chromium.org/b/8860875161330573088
[04:52:40.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:2:0) after 1m0s
[04:52:59.874] Received PubSub notification, asking Buildbucket for the build status
[04:52:59.949] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:40.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:2:0)
[04:53:40.622] Timer tick, asking Buildbucket for the build status
[04:53:40.816] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:40.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:4:0) after 1m0s
[04:54:40.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:4:0)
[04:54:40.810] Timer tick, asking Buildbucket for the build status
[04:54:40.923] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:40.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:5:0) after 1m0s
[04:55:40.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:5:0)
[04:55:40.977] Timer tick, asking Buildbucket for the build status
[04:55:41.073] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:41.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:6:0) after 1m0s
[04:56:41.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:6:0)
[04:56:41.122] Timer tick, asking Buildbucket for the build status
[04:56:41.214] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:41.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:7:0) after 1m0s
[04:57:41.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:7:0)
[04:57:41.233] Timer tick, asking Buildbucket for the build status
[04:57:41.348] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:41.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:8:0) after 1m0s
[04:58:41.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:8:0)
[04:58:41.366] Timer tick, asking Buildbucket for the build status
[04:58:41.484] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:41.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:9:0) after 1m0s
[04:59:41.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:9:0)
[04:59:41.502] Timer tick, asking Buildbucket for the build status
[04:59:41.586] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:41.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:10:0) after 1m0s
[05:00:41.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:10:0)
[05:00:41.608] Timer tick, asking Buildbucket for the build status
[05:00:41.678] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:41.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:11:0) after 1m0s
[05:01:41.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:11:0)
[05:01:41.708] Timer tick, asking Buildbucket for the build status
[05:01:41.776] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:41.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:12:0) after 1m0s
[05:02:41.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:12:0)
[05:02:41.808] Timer tick, asking Buildbucket for the build status
[05:02:41.927] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:41.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:13:0) after 1m0s
[05:03:41.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:13:0)
[05:03:41.946] Timer tick, asking Buildbucket for the build status
[05:03:42.166] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:42.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:14:0) after 1m0s
[05:04:42.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:14:0)
[05:04:42.204] Timer tick, asking Buildbucket for the build status
[05:04:42.270] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:42.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:15:0) after 1m0s
[05:05:42.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:15:0)
[05:05:42.252] Timer tick, asking Buildbucket for the build status
[05:05:42.379] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:42.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:16:0) after 1m0s
[05:06:42.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:16:0)
[05:06:42.424] Timer tick, asking Buildbucket for the build status
[05:06:42.705] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:42.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:17:0) after 1m0s
[05:07:42.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:17:0)
[05:07:42.713] Timer tick, asking Buildbucket for the build status
[05:07:43.158] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:43.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:18:0) after 1m0s
[05:08:43.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:18:0)
[05:08:43.201] Timer tick, asking Buildbucket for the build status
[05:08:43.324] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:43.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:19:0) after 1m0s
[05:09:43.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:19:0)
[05:09:43.342] Timer tick, asking Buildbucket for the build status
[05:09:43.488] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:43.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:20:0) after 1m0s
[05:10:43.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:20:0)
[05:10:43.519] Timer tick, asking Buildbucket for the build status
[05:10:43.741] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:43.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:21:0) after 1m0s
[05:11:43.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:21:0)
[05:11:43.761] Timer tick, asking Buildbucket for the build status
[05:11:43.825] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:43.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:22:0) after 1m0s
[05:12:43.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:22:0)
[05:12:43.837] Timer tick, asking Buildbucket for the build status
[05:12:43.961] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:43.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:23:0) after 1m0s
[05:13:44.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:23:0)
[05:13:44.052] Timer tick, asking Buildbucket for the build status
[05:13:44.112] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:44.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:24:0) after 1m0s
[05:14:44.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:24:0)
[05:14:44.145] Timer tick, asking Buildbucket for the build status
[05:14:44.225] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:44.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:25:0) after 1m0s
[05:15:44.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:25:0)
[05:15:44.410] Timer tick, asking Buildbucket for the build status
[05:15:44.468] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:26:0) after 1m0s
[05:16:44.470] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:26:0)
[05:16:44.470] Timer tick, asking Buildbucket for the build status
[05:16:44.626] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:27:0) after 1m0s
[05:17:44.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:27:0)
[05:17:44.668] Timer tick, asking Buildbucket for the build status
[05:17:44.758] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:44.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:28:0) after 1m0s
[05:18:44.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:28:0)
[05:18:44.777] Timer tick, asking Buildbucket for the build status
[05:18:44.832] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:29:0) after 1m0s
[05:19:44.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:29:0)
[05:19:44.842] Timer tick, asking Buildbucket for the build status
[05:19:45.103] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:30:0) after 1m0s
[05:20:45.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:30:0)
[05:20:45.124] Timer tick, asking Buildbucket for the build status
[05:20:45.195] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:31:0) after 1m0s
[05:21:45.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:31:0)
[05:21:45.226] Timer tick, asking Buildbucket for the build status
[05:21:45.282] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:45.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:32:0) after 1m0s
[05:22:45.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:32:0)
[05:22:45.303] Timer tick, asking Buildbucket for the build status
[05:22:45.419] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:45.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:33:0) after 1m0s
[05:23:45.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:33:0)
[05:23:45.440] Timer tick, asking Buildbucket for the build status
[05:23:45.495] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:45.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:34:0) after 1m0s
[05:24:45.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:34:0)
[05:24:45.516] Timer tick, asking Buildbucket for the build status
[05:24:45.577] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:45.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:35:0) after 1m0s
[05:25:45.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:35:0)
[05:25:45.658] Timer tick, asking Buildbucket for the build status
[05:25:45.734] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:45.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:36:0) after 1m0s
[05:26:45.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:36:0)
[05:26:45.776] Timer tick, asking Buildbucket for the build status
[05:26:45.842] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:37:0) after 1m0s
[05:27:45.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:37:0)
[05:27:45.925] Timer tick, asking Buildbucket for the build status
[05:27:46.022] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:46.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:38:0) after 1m0s
[05:28:46.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:38:0)
[05:28:46.119] Timer tick, asking Buildbucket for the build status
[05:28:46.182] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:46.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:39:0) after 1m0s
[05:29:46.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:39:0)
[05:29:46.174] Timer tick, asking Buildbucket for the build status
[05:29:46.328] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:46.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:40:0) after 1m0s
[05:30:46.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:40:0)
[05:30:46.406] Timer tick, asking Buildbucket for the build status
[05:30:46.485] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:46.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:41:0) after 1m0s
[05:31:46.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:41:0)
[05:31:46.494] Timer tick, asking Buildbucket for the build status
[05:31:46.564] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:46.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:42:0) after 1m0s
[05:32:46.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:42:0)
[05:32:46.712] Timer tick, asking Buildbucket for the build status
[05:32:47.028] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:47.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:43:0) after 1m0s
[05:33:47.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:43:0)
[05:33:47.123] Timer tick, asking Buildbucket for the build status
[05:33:47.221] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:47.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:44:0) after 1m0s
[05:34:47.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:44:0)
[05:34:47.298] Timer tick, asking Buildbucket for the build status
[05:34:47.391] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:47.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:45:0) after 1m0s
[05:35:47.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:45:0)
[05:35:47.395] Timer tick, asking Buildbucket for the build status
[05:35:47.548] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:47.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:46:0) after 1m0s
[05:36:47.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:46:0)
[05:36:47.604] Timer tick, asking Buildbucket for the build status
[05:36:47.739] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:47.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:47:0) after 1m0s
[05:37:47.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:47:0)
[05:37:47.753] Timer tick, asking Buildbucket for the build status
[05:37:48.221] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:48.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:48:0) after 1m0s
[05:38:48.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:48:0)
[05:38:48.243] Timer tick, asking Buildbucket for the build status
[05:38:48.670] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:48.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:49:0) after 1m0s
[05:39:48.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:49:0)
[05:39:48.685] Timer tick, asking Buildbucket for the build status
[05:39:48.813] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:48.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:50:0) after 1m0s
[05:40:48.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:50:0)
[05:40:48.869] Timer tick, asking Buildbucket for the build status
[05:40:48.984] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:48.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:51:0) after 1m0s
[05:41:49.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:51:0)
[05:41:49.005] Timer tick, asking Buildbucket for the build status
[05:41:49.417] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:49.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:52:0) after 1m0s
[05:42:49.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:52:0)
[05:42:49.496] Timer tick, asking Buildbucket for the build status
[05:42:49.604] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:49.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:53:0) after 1m0s
[05:43:49.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:53:0)
[05:43:49.658] Timer tick, asking Buildbucket for the build status
[05:43:49.779] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:49.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:54:0) after 1m0s
[05:44:49.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:54:0)
[05:44:49.802] Timer tick, asking Buildbucket for the build status
[05:44:50.038] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:50.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:55:0) after 1m0s
[05:45:50.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:55:0)
[05:45:50.072] Timer tick, asking Buildbucket for the build status
[05:45:50.148] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:50.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:56:0) after 1m0s
[05:46:50.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:56:0)
[05:46:50.170] Timer tick, asking Buildbucket for the build status
[05:46:50.281] Build 8860875161330573088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:50.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026305223508049632:57:0) after 1m0s
[05:47:36.764] Received PubSub notification, asking Buildbucket for the build status
[05:47:36.832] Build 8860875161330573088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:47:36.832] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608011254470649",
"created_by": "project:chromium",
"created_ts": "1608007959964748",
"id": "8860875161330573088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3909caf2d715f4efd5556ea87186bba9d7535284\"}}",
"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\": \"3909caf2d715f4efd5556ea87186bba9d7535284\", \"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-48-1qrt\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43726, \"got_angle_revision\": \"cb8903b14336d86e3d0889a367df60f417910872\", \"got_dawn_revision\": \"43c39a1ba42a1b1bc94337b11fe744fc36663693\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"3909caf2d715f4efd5556ea87186bba9d7535284\", \"got_revision_cp\": \"refs/heads/master@{#836969}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"fa44979d90aac8cca42e2fee711d44f23c9c4d67\", \"got_v8_revision\": \"8a79762d41dd3e32ad22991883d17e5036a66b73\", \"got_v8_revision_cp\": \"refs/heads/8.9.148@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"3909caf2d715f4efd5556ea87186bba9d7535284\", \"swarm_hashes_refs/heads/master(at){#836969}_without_patch\": {\"absl_hardening_tests\": \"4bf478104a9b27264dc87ef70cba7c30e7e1863c\", \"accessibility_unittests\": \"33858bd659fe5ce88946ddfa5d08964f777ac8ba\", \"angle_unittests\": \"1e28ee51d219265b38152915064dca116c8f72ba\", \"app_list_unittests\": \"c9aea252de32601f181654cd3f0868875a032135\", \"app_shell_unittests\": \"5f7563b69d51f5c6f8c9056ee0de530be7efa7fe\", \"ash_unittests\": \"4d0a9a33fbfb2c138ead51d578f4e6a79c292911\", \"aura_unittests\": \"a4434e9ff7a6e0af36ee1923d6c2e75f1f734d36\", \"base_unittests\": \"a9a91f23da6c3486c452d53ca4c151813213fc25\", \"base_util_unittests\": \"674b70cbfbbe83cb25a7b5809c8eabb6a87d86d7\", \"blink_common_unittests\": \"321bae38ee093cc6a92265285abaf198ae1228a0\", \"blink_fuzzer_unittests\": \"b3b1d0b357345a7d5344debea0cbf9de9a8e8798\", \"blink_heap_unittests\": \"5e150e620ee211752f600adf15af3f56944e7a0b\", \"blink_platform_unittests\": \"d5b9a1bf8f8b24299df78009c3bd3162f54062de\", \"blink_unittests\": \"1e75458586ba8a54976ca3fc8201424e11d02225\", \"boringssl_crypto_tests\": \"f169666994e613e1d32871fa1bac35e6a05bd8f8\", \"boringssl_ssl_tests\": \"e884b4ab617b0491d0cdfb0e69d558988c7ffa1c\", \"browser_tests\": \"9a4e2d1327480cac9dbf8c19b2df69ea5a35da6f\", \"capture_unittests\": \"3bfb658322d0bdc278f3d09e8db8aa9ef411db4e\", \"cast_unittests\": \"7980d0f60079325bb6032ff685e050de2950f8db\", \"cc_unittests\": \"743040bd1aded4da435a169e9abcfea50c6b3a26\", \"chrome_app_unittests\": \"a4095a176d2a83a10624921001ebac2aae484d96\", \"chromedriver_unittests\": \"e86dc035fe0fe8b466ec6f6f1b743e6ee229426d\", \"chromeos_components_unittests\": \"9dbea17ae115377168d2a47d0ecf55993618eda4\", \"chromeos_unittests\": \"aa39cffcad2988a66ddfac3a604f931afec7bf56\", \"components_browsertests\": \"eeb07c86882f9549c2cd8341caa1cd75001a8118\", \"components_unittests\": \"02c9397632629948d5a93bb33dc57a6e9b452100\", \"compositor_unittests\": \"cecbe2d9c18ab6fd1dbc52161c3ee4704e9a5bcc\", \"content_browsertests\": \"0f6b1f7dec3a46bf1ace6d1898bd4a3516c9a8e6\", \"content_unittests\": \"b09336cd6b105906cc65b0c38466478bb81b5372\", \"crashpad_tests\": \"8365ecb5bb85664f13a5f5dae038c4e0f787e9d3\", \"crypto_unittests\": \"3214bd5080a5662c4fd33fd7b49118c5bd9716b1\", \"dbus_unittests\": \"05d8dde4869bd0e3206270e072387885ee7f1813\", \"device_unittests\": \"fc04f188d663dae44afcd042647205919fb935b1\", \"display_unittests\": \"c1cce533c97acac054c70b47cf70f8d683fc7d00\", \"events_unittests\": \"44e81566d6869e5420deb2328e01091cffea9915\", \"exo_unittests\": \"42ebc72a558e5b43ddc3cdf1969702ec7fd085e7\", \"extensions_browsertests\": \"dd8de992565ef8eb942e410af7a25b15b57bc8ca\", \"extensions_unittests\": \"5f2a49cbfe24fd1d796caac20de125e0b44f23d8\", \"filesystem_service_unittests\": \"542c2219e68d5eac4425ebc0a080b2ec64256885\", \"gcm_unit_tests\": \"e3d8e9d6a3f286c97935f06c752e557ccccdcf4a\", \"gfx_unittests\": \"6bf8ae333519d2e9a02168c9f93928377dc85774\", \"gin_unittests\": \"1a96263a6899c0d76a35cc9eb03863f6abd5046a\", \"gl_unittests_ozone\": \"6ba19b49142b19f2cedcd2459ab79d0600028580\", \"google_apis_unittests\": \"b8a489477da16046d516d28fc3b9f7a969c6cbce\", \"gpu_unittests\": \"84ef6e4c585990a337c52d680029422ef8ab4131\", \"gwp_asan_unittests\": \"c62d2776519ba613c9f0b5122a5cbc351004b3e9\", \"interactive_ui_tests\": \"378d857efa0736b5eb9f29873543c928d255df48\", \"ipc_tests\": \"0ec30751e5e0aeea9546537f37d881335fe456b0\", \"jingle_unittests\": \"acb707c311e36f5f53c0b5a899c732bf77a539f7\", \"keyboard_unittests\": \"78852b49168917bfefca9425ef23354f937fedd5\", \"latency_unittests\": \"6f79dfc6a422cfe854a132bc5e780034709fd17a\", \"libjingle_xmpp_unittests\": \"79d72948326e9ce516924d0f4a6215afa0d63c78\", \"liburlpattern_unittests\": \"e34d281925b51d7a36d827eccf44b0be065b49c2\", \"media_blink_unittests\": \"8721e544f57671597e434fb4504d25b6df57cebd\", \"media_unittests\": \"1117c1c187d8e5c5b70031310ab8071e037e5601\", \"message_center_unittests\": \"50f6573eeb62e73c39782e78069f9729263b85eb\", \"midi_unittests\": \"dc43223490d0858697a17961eb85e576a61735d5\", \"mojo_core_unittests\": \"f75f25596b3a9fe739daafd0386c1cefdc09a1e0\", \"mojo_unittests\": \"b0ef22788f2834bbb436a5ce1bc68031fe57ec85\", \"nacl_helper_nonsfi_unittests\": \"e4147976ce5b4c665064ffa463df18d1ae2a4fad\", \"nacl_loader_unittests\": \"603235819e074ac2dd17609019849ab98778f279\", \"native_theme_unittests\": \"c6dc5e01bd78fb8d1dfe5299f62aea6d7cd3de49\", \"net_unittests\": \"44cdcfc1156021e4d5946adf28a1047056319979\", \"ozone_gl_unittests\": \"4dfb93864adee2d5d0d842fec320ced42121bffc\", \"ozone_unittests\": \"58c39fe82191c26c0c4f8943e262e720012988a4\", \"ozone_x11_unittests\": \"54cca6819155778b2a0f5e9c3d7cf3014214e015\", \"pdf_unittests\": \"3f1f457ec30d3a31745279b054947504f4246de9\", \"perfetto_unittests\": \"1d391249849b3a5b2cab2371e40d3b3a9e473215\", \"ppapi_unittests\": \"f10c6760c1fd16df336e7c64b57029ea02ca427b\", \"printing_unittests\": \"75a1e8dfac3936e07b45568a007f5911a939b7e0\", \"remoting_unittests\": \"3e76e994e7d5e5e6786fbd8c23d6eb55a77fa8e9\", \"sandbox_linux_unittests\": \"1b5ff30fdf58bc34f2bfe77c942e8348272680b4\", \"service_manager_unittests\": \"dcd638d2acfdc5b037ccdf229ac741cba45ec8ef\", \"services_unittests\": \"34e58204db52656d581dc1bead1d87fe72997b12\", \"shell_dialogs_unittests\": \"6bd0b47ce93165ef7131a1ac7e9fb668087d9597\", \"shell_encryption_unittests\": \"a2c822160bec7eb8dedaa30838a546a5e79db075\", \"skia_unittests\": \"72eb2f8b908e63495799b0b05b0e4c260ff1aacc\", \"snapshot_unittests\": \"294b1149be1a57256eccec2dda7296861443d509\", \"sql_unittests\": \"9a227dc973fd4200cde0015d3667805df07497a4\", \"storage_unittests\": \"71481f672c4046d09b10463f44c55c8174f25ae0\", \"sync_integration_tests\": \"8f60f3f594d5aed850797bfe6f2ee0f833c38074\", \"traffic_annotation_auditor_unittests\": \"39e2988ee2715cd243a55da9e98bb324b542b1be\", \"ui_base_unittests\": \"5c1e13eeb44ba46759eec3ef5d5a438b9cabc275\", \"ui_chromeos_unittests\": \"15337bde70a3504e24592576a4f158deca6b64d9\", \"ui_touch_selection_unittests\": \"3ba160eabe396ceaca9476037bcbcc41c12e0a0e\", \"unit_tests\": \"2a5448857972ccef35fb178eeef8010c616e620c\", \"url_unittests\": \"67a1b092f2deedb1cea05fbd5c28b2158d2597a2\", \"usage_time_limit_unittests\": \"702488d29b89956f980074338273be829ad4cf5c\", \"views_unittests\": \"b0cead7d93015d36f9206d776d8d0f7433c9c54a\", \"viz_unittests\": \"0cff8b756d4cc2190488f6ba9891a22efc09f73a\", \"wayland_client_perftests\": \"efe113b1b1ae6430cca5f96c323149d1f3343503\", \"wm_unittests\": \"321bfca21518c25306e40bea404dba4ca8b48548\", \"wtf_unittests\": \"5fa7f07ad0d569dee6f2bdf2d1a27be25318b68f\", \"zlib_unittests\": \"544a70d013e94fe1400ec9a2d208c09591632b24\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_c5a4a77f7d90ed750e2e3c196231723a8f9435afca7311d8fa2536a9dada12e3_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-48-1qrt\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608007979141178",
"status": "COMPLETED",
"status_changed_ts": "1608011255309471",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43726",
"builder:linux-chromeos-rel",
"buildset:commit/git/3909caf2d715f4efd5556ea87186bba9d7535284",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3909caf2d715f4efd5556ea87186bba9d7535284",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026305223508049632",
"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/8860875161330573088/+/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:507d8fd26d9b8e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608011255425077",
"url": "https://ci.chromium.org/b/8860875161330573088",
"utcnow_ts": "1608011256824458"
}
}
[05:47:36.832] Invocation finished in 54m58.328121218s with status SUCCEEDED