[16:05:37.570] New invocation is queued and will start shortly
[16:05:38.772] Starting the invocation (attempt 1)
[16:05:38.827] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:05:38.827] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:05:38.827] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027621837501039760",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027621837501039760",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"gitiles_ref:refs/heads/master"
]
}
[16:05:40.716] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606752338994429",
"id": "8862191775344798624",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606752340326384",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43311",
"builder:linux-chromeos-rel",
"buildset:commit/git/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027621837501039760",
"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/8862191775344798624/+/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": "1606752340326678",
"url": "https://ci.chromium.org/b/8862191775344798624",
"utcnow_ts": "1606752340689953"
}
}
[16:05:40.716] Task URL: https://ci.chromium.org/b/8862191775344798624
[16:05:40.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:2:0) after 1m0s
[16:05:42.861] Received PubSub notification, asking Buildbucket for the build status
[16:05:45.617] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:40.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:2:0)
[16:06:40.827] Timer tick, asking Buildbucket for the build status
[16:06:40.936] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:40.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:4:0) after 1m0s
[16:07:40.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:4:0)
[16:07:40.956] Timer tick, asking Buildbucket for the build status
[16:07:41.103] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:41.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:5:0) after 1m0s
[16:08:41.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:5:0)
[16:08:41.131] Timer tick, asking Buildbucket for the build status
[16:08:42.948] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:42.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:6:0) after 1m0s
[16:09:42.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:6:0)
[16:09:42.976] Timer tick, asking Buildbucket for the build status
[16:09:43.192] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:43.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:7:0) after 1m0s
[16:10:43.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:7:0)
[16:10:43.238] Timer tick, asking Buildbucket for the build status
[16:10:43.354] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:43.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:8:0) after 1m0s
[16:11:43.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:8:0)
[16:11:43.372] Timer tick, asking Buildbucket for the build status
[16:11:43.516] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:43.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:9:0) after 1m0s
[16:12:43.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:9:0)
[16:12:43.534] Timer tick, asking Buildbucket for the build status
[16:12:43.657] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:43.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:10:0) after 1m0s
[16:13:43.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:10:0)
[16:13:43.723] Timer tick, asking Buildbucket for the build status
[16:13:43.791] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:43.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:11:0) after 1m0s
[16:14:43.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:11:0)
[16:14:43.876] Timer tick, asking Buildbucket for the build status
[16:14:44.392] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:44.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:12:0) after 1m0s
[16:15:44.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:12:0)
[16:15:44.503] Timer tick, asking Buildbucket for the build status
[16:15:46.287] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:46.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:13:0) after 1m0s
[16:16:46.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:13:0)
[16:16:46.352] Timer tick, asking Buildbucket for the build status
[16:16:46.423] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:46.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:14:0) after 1m0s
[16:17:46.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:14:0)
[16:17:46.434] Timer tick, asking Buildbucket for the build status
[16:17:46.537] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:46.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:15:0) after 1m0s
[16:18:46.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:15:0)
[16:18:46.521] Timer tick, asking Buildbucket for the build status
[16:18:46.605] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:46.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:16:0) after 1m0s
[16:19:46.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:16:0)
[16:19:46.682] Timer tick, asking Buildbucket for the build status
[16:19:46.892] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:46.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:17:0) after 1m0s
[16:20:47.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:17:0)
[16:20:47.081] Timer tick, asking Buildbucket for the build status
[16:20:47.271] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:47.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:18:0) after 1m0s
[16:21:47.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:18:0)
[16:21:47.405] Timer tick, asking Buildbucket for the build status
[16:21:47.554] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:47.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:19:0) after 1m0s
[16:22:47.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:19:0)
[16:22:47.674] Timer tick, asking Buildbucket for the build status
[16:22:47.868] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:47.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:20:0) after 1m0s
[16:23:47.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:20:0)
[16:23:47.920] Timer tick, asking Buildbucket for the build status
[16:23:47.988] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:47.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:21:0) after 1m0s
[16:24:47.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:21:0)
[16:24:47.996] Timer tick, asking Buildbucket for the build status
[16:24:48.066] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:48.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:22:0) after 1m0s
[16:25:48.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:22:0)
[16:25:48.077] Timer tick, asking Buildbucket for the build status
[16:25:48.330] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:48.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:23:0) after 1m0s
[16:26:48.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:23:0)
[16:26:48.419] Timer tick, asking Buildbucket for the build status
[16:26:48.484] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:48.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:24:0) after 1m0s
[16:27:48.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:24:0)
[16:27:48.516] Timer tick, asking Buildbucket for the build status
[16:27:48.650] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:48.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:25:0) after 1m0s
[16:28:48.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:25:0)
[16:28:48.799] Timer tick, asking Buildbucket for the build status
[16:28:48.878] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:48.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:26:0) after 1m0s
[16:29:48.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:26:0)
[16:29:48.907] Timer tick, asking Buildbucket for the build status
[16:29:48.996] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:48.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:27:0) after 1m0s
[16:30:49.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:27:0)
[16:30:49.072] Timer tick, asking Buildbucket for the build status
[16:30:49.235] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:49.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:28:0) after 1m0s
[16:31:49.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:28:0)
[16:31:49.284] Timer tick, asking Buildbucket for the build status
[16:31:49.362] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:49.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:29:0) after 1m0s
[16:32:49.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:29:0)
[16:32:49.396] Timer tick, asking Buildbucket for the build status
[16:32:49.529] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:49.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:30:0) after 1m0s
[16:33:49.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:30:0)
[16:33:49.550] Timer tick, asking Buildbucket for the build status
[16:33:49.643] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:49.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:31:0) after 1m0s
[16:34:49.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:31:0)
[16:34:49.635] Timer tick, asking Buildbucket for the build status
[16:34:49.737] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:49.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:32:0) after 1m0s
[16:35:49.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:32:0)
[16:35:49.774] Timer tick, asking Buildbucket for the build status
[16:35:49.880] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:49.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:33:0) after 1m0s
[16:36:50.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:33:0)
[16:36:50.009] Timer tick, asking Buildbucket for the build status
[16:36:50.142] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:50.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:34:0) after 1m0s
[16:37:50.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:34:0)
[16:37:50.378] Timer tick, asking Buildbucket for the build status
[16:37:50.943] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:50.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:35:0) after 1m0s
[16:38:51.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:35:0)
[16:38:51.043] Timer tick, asking Buildbucket for the build status
[16:38:51.203] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:51.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:36:0) after 1m0s
[16:39:51.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:36:0)
[16:39:51.392] Timer tick, asking Buildbucket for the build status
[16:39:51.587] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:51.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:37:0) after 1m0s
[16:40:51.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:37:0)
[16:40:51.699] Timer tick, asking Buildbucket for the build status
[16:40:51.926] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:51.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:38:0) after 1m0s
[16:41:52.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:38:0)
[16:41:52.049] Timer tick, asking Buildbucket for the build status
[16:41:52.187] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:52.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:39:0) after 1m0s
[16:42:52.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:39:0)
[16:42:52.328] Timer tick, asking Buildbucket for the build status
[16:42:52.517] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:52.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:40:0) after 1m0s
[16:43:52.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:40:0)
[16:43:52.591] Timer tick, asking Buildbucket for the build status
[16:43:52.867] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:52.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:41:0) after 1m0s
[16:44:52.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:41:0)
[16:44:52.886] Timer tick, asking Buildbucket for the build status
[16:44:53.016] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:53.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:42:0) after 1m0s
[16:45:53.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:42:0)
[16:45:53.039] Timer tick, asking Buildbucket for the build status
[16:45:53.699] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:53.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:43:0) after 1m0s
[16:46:53.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:43:0)
[16:46:53.746] Timer tick, asking Buildbucket for the build status
[16:46:54.243] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:54.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:44:0) after 1m0s
[16:47:54.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:44:0)
[16:47:54.350] Timer tick, asking Buildbucket for the build status
[16:47:54.453] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:54.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:45:0) after 1m0s
[16:48:54.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:45:0)
[16:48:54.599] Timer tick, asking Buildbucket for the build status
[16:48:55.006] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:55.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:46:0) after 1m0s
[16:49:55.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:46:0)
[16:49:55.117] Timer tick, asking Buildbucket for the build status
[16:49:55.849] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:55.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:47:0) after 1m0s
[16:50:55.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:47:0)
[16:50:55.871] Timer tick, asking Buildbucket for the build status
[16:50:56.442] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:56.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:48:0) after 1m0s
[16:51:56.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:48:0)
[16:51:56.487] Timer tick, asking Buildbucket for the build status
[16:51:57.087] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:57.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:49:0) after 1m0s
[16:52:57.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:49:0)
[16:52:57.186] Timer tick, asking Buildbucket for the build status
[16:52:57.335] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:57.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:50:0) after 1m0s
[16:53:57.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:50:0)
[16:53:57.398] Timer tick, asking Buildbucket for the build status
[16:53:58.369] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:58.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:51:0) after 1m0s
[16:54:58.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:51:0)
[16:54:58.400] Timer tick, asking Buildbucket for the build status
[16:54:58.511] Build 8862191775344798624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:58.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027621837501039760:52:0) after 1m0s
[16:54:59.820] Received PubSub notification, asking Buildbucket for the build status
[16:54:59.919] Build 8862191775344798624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:54:59.919] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606755298804969",
"created_by": "project:chromium",
"created_ts": "1606752338994429",
"id": "8862191775344798624",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\"}}",
"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\": \"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-12-hq68\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43311, \"got_angle_revision\": \"3d061021c920ada75c5d9ab2424ca5a037607212\", \"got_dawn_revision\": \"d8bb0bb7c735173219d7a8ffec18eee1813ff302\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\", \"got_revision_cp\": \"refs/heads/master@{#831855}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"ac4c4dc02664bb5a541f817619e037f60a4b2437\", \"got_v8_revision_cp\": \"refs/heads/8.9.69@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc6b0c39e788a31b0ced58c026d8bb0d77bb0285\", \"swarm_hashes_refs/heads/master(at){#831855}_without_patch\": {\"absl_hardening_tests\": \"af561aed37a659b91cbe394df37a1823d42e5aae\", \"accessibility_unittests\": \"068df705c194d027875f69875e4ec4697df6b84d\", \"angle_unittests\": \"eb04d1c7c9fdb1626d5cdce205692806264a11b5\", \"app_list_unittests\": \"ceffe1a14243ca54268ab93acc8d30e3eb4a350b\", \"app_shell_unittests\": \"d58df3709f68d537eee5a165a6d1726c3d2b98d3\", \"ash_unittests\": \"8739099637b80f2b49e552bd123e0293fc68ee06\", \"aura_unittests\": \"a28c4576361c8c1054570ddd430d5742695a12ee\", \"base_unittests\": \"c3e1e5a464f83c0ba7e2c208a955d2f651e6b85b\", \"base_util_unittests\": \"333f2db6230dc350901c94fb0f2edb019313e6bd\", \"blink_common_unittests\": \"7e58780f435af0ceb8737f849e2b64bdac7d5aaf\", \"blink_fuzzer_unittests\": \"1b4e4ad60185abec252a38451c42275755d2ea89\", \"blink_heap_unittests\": \"d215b31fec06f89453be19d395045497a1af6867\", \"blink_platform_unittests\": \"3891a7f11c1874eb2245827ac00aef3f941666c6\", \"blink_unittests\": \"af23d3a911ecdd5541fe8cebc23d70f5daea3967\", \"boringssl_crypto_tests\": \"d7c1eebfb1f0d72ae9dbbfb4809075aaae3a9a4f\", \"boringssl_ssl_tests\": \"55011fec1c20000277323b93614a3d51ac69900b\", \"browser_tests\": \"d617c237e1329858cdc2f571595e97e0e6ce3a87\", \"capture_unittests\": \"f63de6477c35e5619c8bfd4f3de2b1111aea6bf5\", \"cast_unittests\": \"8345e484ddd8d3983f3688e65a0257cf863d9621\", \"cc_unittests\": \"b6f68494ed607ae1053e7549744705a395701c93\", \"chrome_app_unittests\": \"44e5fa9ea6e1a12f2e06d9841553b0d20a5d1927\", \"chromedriver_unittests\": \"78cb7523bd814bb98279c15c070c10ba13622b17\", \"chromeos_components_unittests\": \"b1e78175213ae7129b46422caa19aa4ddfcc2da8\", \"chromeos_unittests\": \"47b9e05af9993e3cc9fdbecdb4e39c35585567e0\", \"components_browsertests\": \"6f3368eddb400a0f10f0f4aa827df2c3972ce8ed\", \"components_unittests\": \"ce1d785a0c2c12275171c647b2840cf77fe2d6a8\", \"compositor_unittests\": \"f9b752f7dfcb2c3d3986ed3da086247362ab8061\", \"content_browsertests\": \"b1b92e20f8cbc4fd10697611204bef4f381a8300\", \"content_unittests\": \"d945553759c9112f4015b367b533f6f001ced0a4\", \"crashpad_tests\": \"d097433d14f08643b3492134de56b8d263734285\", \"crypto_unittests\": \"9ab643abf35fc8cb5e5d9eda8b4b50487ac60233\", \"dbus_unittests\": \"12dadfc9f790dc3ff5b5f92ee6cc90513450cc26\", \"device_unittests\": \"26caf2a5f383360425ac020fd7d145207cca2b59\", \"display_unittests\": \"1ab7755a42b461d94e0e90fb7cf1ecac226a22e5\", \"events_unittests\": \"885c85389bc59648e8d6f342defc00edb77af183\", \"exo_unittests\": \"88112f85b9e214e88103ad834b0fcfb9143e2d7f\", \"extensions_browsertests\": \"21a1234f1293e26e575174929b8890624ba4764a\", \"extensions_unittests\": \"5178c635e44f7891c812fe5e3039f3dc4dfa8cfe\", \"filesystem_service_unittests\": \"d42c62ab6f96783a77b1f2cc650336689de6cd7c\", \"gcm_unit_tests\": \"40741292333f30b0720e22ddf6fe340b1e1aad5e\", \"gfx_unittests\": \"8c5348669805e3195791cd24aec2025e3a5ad1ac\", \"gin_unittests\": \"347549a2d58538f0c8e5a4fc6cd68a9d0cb2127c\", \"gl_unittests_ozone\": \"b833476ddf287eecf9f734032679bd40a42529ff\", \"google_apis_unittests\": \"5db7b333d14659f17294f6ec196b268275c2a169\", \"gpu_unittests\": \"5311f428014711358566bc91a382bf6dc054cfa0\", \"gwp_asan_unittests\": \"801402279a5932c69041bb8d70d091247c0712ca\", \"interactive_ui_tests\": \"5851834c2b24f76aaa7117a9181495235f8bf786\", \"ipc_tests\": \"092d0ed593d0015749e7fca82acbed04ac876ed8\", \"jingle_unittests\": \"7f60f36152cbec8505b24954aebd0f418b64c0c9\", \"keyboard_unittests\": \"c1cbb821ed1c3610409785cdbacbbf9334570ef8\", \"latency_unittests\": \"e940a837431d626c1607571fd6d114ebe0bd4414\", \"libjingle_xmpp_unittests\": \"a5b769447b40d75cdd96f02e5a4d2a46ac97406f\", \"liburlpattern_unittests\": \"d8f00bc95a6bedaa3fb340a1491f86cdb08bb140\", \"media_blink_unittests\": \"eac57fb8eea4f8a6a7566e448138b6350a871f30\", \"media_unittests\": \"d2efae4762c2245f25932d70b1233b54b751d892\", \"message_center_unittests\": \"9b4bd072844095f21c7f634d39736a9ff35f0e13\", \"midi_unittests\": \"0373f987f0e1d9247ccc2a5410dbf5a71827d85f\", \"mojo_core_unittests\": \"ce56825db202387af72463e5a5183347771369d6\", \"mojo_unittests\": \"6a5aba17742d6fc4d7899010da6448520f874e14\", \"nacl_helper_nonsfi_unittests\": \"12d527329b765586d07b9090ed1d8c721d5fcc42\", \"nacl_loader_unittests\": \"58373126374dc65fc1d94f742b9b821f492416c1\", \"native_theme_unittests\": \"1487250bae697d94008118153cb23af8193922fc\", \"net_unittests\": \"e47281918551629b7543fb55a8fa6d7a962ec064\", \"ozone_gl_unittests\": \"362ee51cc6b982193f315c3f33abd38ed2c48c20\", \"ozone_unittests\": \"d0ab93e6cd8cb5ae9b227320ead9b5c8128d9d39\", \"ozone_x11_unittests\": \"c3af6b3a9041593cdf27c084245f98439090e4b9\", \"pdf_unittests\": \"fcef69280818c35d449f8e9bc68be94d8ff3eff6\", \"perfetto_unittests\": \"ce1bac9b45d54025d2a6b0c9cb79e29a6ee2e1ff\", \"ppapi_unittests\": \"a7bb96e12cfd1ad821f9c3fe80caf2d4b1156c94\", \"printing_unittests\": \"891716ca711692faf356d75597a9cc6cda675476\", \"remoting_unittests\": \"15ea77cae359a491857fae1e390a75447c536c33\", \"sandbox_linux_unittests\": \"9e49185b55374fac04257d505a9c5ee11b524174\", \"service_manager_unittests\": \"12162e7b946e8627b1d1b0975ab4027805fbcfda\", \"services_unittests\": \"66507af3a8c62626c439c42962f6d8279ef3de1d\", \"shell_dialogs_unittests\": \"6272c2eb4229a9d7cb9a0173be867dc39daf52e7\", \"skia_unittests\": \"4b275c86bf575b3b3efdc459992d4381d87cbd6d\", \"snapshot_unittests\": \"00d9dcb62c1b015f68ac242d07b0444bb16ae0b2\", \"sql_unittests\": \"4506fc13d23f719b6682e549422b72b1b6788081\", \"storage_unittests\": \"f6026ababd23e4f25cab24e853ec645b403470be\", \"sync_integration_tests\": \"3b727d1f1faa5230c849644188823e93bcd026c7\", \"traffic_annotation_auditor_unittests\": \"d494b2e2dfbfcd4561fd94b0ff71fd716e821c5f\", \"ui_base_unittests\": \"5aff2535994671917ef8a2e18579c791963091f1\", \"ui_chromeos_unittests\": \"a9501ecd9791602367558c515722434e6aeef0b2\", \"ui_touch_selection_unittests\": \"abab979a5f774b369e998203f79e38bdc85ae2f6\", \"unit_tests\": \"db39c60d141f6ca38ae674643e328896012d59f6\", \"url_unittests\": \"79366cdbca04219402d45425a733d8e98a96afa0\", \"usage_time_limit_unittests\": \"fa5f6e4c76b85fe8981d1b3d2b1ffa9188390904\", \"views_unittests\": \"77776f285e51c5d00901531e80e6f751f829a86a\", \"viz_unittests\": \"a544081f05f28fa29cfb056c895f3e2aef1d94da\", \"wayland_client_perftests\": \"b75889552ea5103057a391ab209c1433a5e9b6d0\", \"wm_unittests\": \"cb081aeffb3758c3fcf9cda4d2ad9b1c42579069\", \"wtf_unittests\": \"f827efba0d295ef20930210b703b263699acf68b\", \"zlib_unittests\": \"89e1a186a2e4aca73864956501da3c290c2a8cb1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1313787165392eb219c57f145b1b9e0fbbb62d544385caa60e27617303515107_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_609b54f795c46f6da98bce3e3c3edeb2c2e2d72c7759cc5040a86edccd734c7c_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_d1ade7eeaf5d4bfec46cdcc4260ff7266c5ca5ac9f935f1643683e9e2b66e303_v2\", \"builder_e7fd4b7a9e211f8d968af60263c508e13e49814167aff980c2bf239c05ea48ef_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-12-hq68\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606752342248429",
"status": "COMPLETED",
"status_changed_ts": "1606755299206237",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43311",
"builder:linux-chromeos-rel",
"buildset:commit/git/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc6b0c39e788a31b0ced58c026d8bb0d77bb0285",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027621837501039760",
"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/8862191775344798624/+/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:5032b894f4399310",
"user_agent:luci-scheduler"
],
"updated_ts": "1606755299215877",
"url": "https://ci.chromium.org/b/8862191775344798624",
"utcnow_ts": "1606755299909267"
}
}
[16:54:59.919] Invocation finished in 49m22.36422823s with status SUCCEEDED