[23:30:15.190] New invocation is queued and will start shortly
[23:30:16.543] Starting the invocation (attempt 1)
[23:30:16.579] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:30:16.579] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:30:16.579] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018987152181668080",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"76fede2c70a62f81ff7ba138b8d074c0059fb67b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018987152181668080",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"gitiles_ref:refs/heads/master"
]
}
[23:30:17.424] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614987016683690",
"id": "8853557089952878336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"76fede2c70a62f81ff7ba138b8d074c0059fb67b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614987017124164",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45944",
"builder:linux-chromeos-rel",
"buildset:commit/git/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018987152181668080",
"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/8853557089952878336/+/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": "1614987017124379",
"url": "https://ci.chromium.org/b/8853557089952878336",
"utcnow_ts": "1614987017412570"
}
}
[23:30:17.424] Task URL: https://ci.chromium.org/b/8853557089952878336
[23:30:17.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:2:0) after 1m0s
[23:30:23.697] Received PubSub notification, asking Buildbucket for the build status
[23:30:23.903] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:17.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:2:0)
[23:31:17.499] Timer tick, asking Buildbucket for the build status
[23:31:17.752] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:17.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:4:0) after 1m0s
[23:32:17.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:4:0)
[23:32:17.942] Timer tick, asking Buildbucket for the build status
[23:32:18.006] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:18.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:5:0) after 1m0s
[23:33:18.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:5:0)
[23:33:18.084] Timer tick, asking Buildbucket for the build status
[23:33:18.259] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:6:0) after 1m0s
[23:34:18.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:6:0)
[23:34:18.280] Timer tick, asking Buildbucket for the build status
[23:34:18.350] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:18.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:7:0) after 1m0s
[23:35:18.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:7:0)
[23:35:18.455] Timer tick, asking Buildbucket for the build status
[23:35:18.554] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:18.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:8:0) after 1m0s
[23:36:19.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:8:0)
[23:36:19.281] Timer tick, asking Buildbucket for the build status
[23:36:19.354] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:19.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:9:0) after 1m0s
[23:37:21.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:9:0)
[23:37:21.707] Timer tick, asking Buildbucket for the build status
[23:37:21.798] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:10:0) after 1m0s
[23:38:22.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:10:0)
[23:38:22.148] Timer tick, asking Buildbucket for the build status
[23:38:22.225] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:22.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:11:0) after 1m0s
[23:39:22.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:11:0)
[23:39:22.423] Timer tick, asking Buildbucket for the build status
[23:39:22.496] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:22.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:12:0) after 1m0s
[23:40:23.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:12:0)
[23:40:23.036] Timer tick, asking Buildbucket for the build status
[23:40:23.118] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:13:0) after 1m0s
[23:41:23.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:13:0)
[23:41:23.523] Timer tick, asking Buildbucket for the build status
[23:41:23.594] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:23.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:14:0) after 1m0s
[23:42:24.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:14:0)
[23:42:24.019] Timer tick, asking Buildbucket for the build status
[23:42:24.118] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:24.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:15:0) after 1m0s
[23:43:25.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:15:0)
[23:43:25.355] Timer tick, asking Buildbucket for the build status
[23:43:25.434] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:25.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:16:0) after 1m0s
[23:44:25.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:16:0)
[23:44:25.785] Timer tick, asking Buildbucket for the build status
[23:44:25.899] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:25.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:17:0) after 1m0s
[23:45:26.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:17:0)
[23:45:26.178] Timer tick, asking Buildbucket for the build status
[23:45:26.274] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:18:0) after 1m0s
[23:46:26.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:18:0)
[23:46:26.427] Timer tick, asking Buildbucket for the build status
[23:46:26.632] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:26.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:19:0) after 1m0s
[23:47:26.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:19:0)
[23:47:26.893] Timer tick, asking Buildbucket for the build status
[23:47:26.965] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:26.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:20:0) after 1m0s
[23:48:27.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:20:0)
[23:48:27.173] Timer tick, asking Buildbucket for the build status
[23:48:27.278] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:27.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:21:0) after 1m0s
[23:49:27.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:21:0)
[23:49:27.338] Timer tick, asking Buildbucket for the build status
[23:49:27.505] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:27.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:22:0) after 1m0s
[23:50:27.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:22:0)
[23:50:27.519] Timer tick, asking Buildbucket for the build status
[23:50:27.658] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:27.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:23:0) after 1m0s
[23:51:27.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:23:0)
[23:51:27.712] Timer tick, asking Buildbucket for the build status
[23:51:27.796] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:27.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:24:0) after 1m0s
[23:52:27.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:24:0)
[23:52:27.898] Timer tick, asking Buildbucket for the build status
[23:52:28.289] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:28.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:25:0) after 1m0s
[23:53:28.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:25:0)
[23:53:28.292] Timer tick, asking Buildbucket for the build status
[23:53:28.377] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:28.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:26:0) after 1m0s
[23:54:28.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:26:0)
[23:54:28.396] Timer tick, asking Buildbucket for the build status
[23:54:28.468] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:28.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:27:0) after 1m0s
[23:55:28.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:27:0)
[23:55:28.538] Timer tick, asking Buildbucket for the build status
[23:55:29.133] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:28:0) after 1m0s
[23:56:29.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:28:0)
[23:56:29.222] Timer tick, asking Buildbucket for the build status
[23:56:29.347] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:29.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:29:0) after 1m0s
[23:57:29.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:29:0)
[23:57:29.369] Timer tick, asking Buildbucket for the build status
[23:57:29.441] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:29.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:30:0) after 1m0s
[23:58:30.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:30:0)
[23:58:30.581] Timer tick, asking Buildbucket for the build status
[23:58:30.647] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:30.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:31:0) after 1m0s
[23:59:30.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:31:0)
[23:59:30.912] Timer tick, asking Buildbucket for the build status
[23:59:31.014] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:31.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:32:0) after 1m0s
[00:00:31.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:32:0)
[00:00:31.325] Timer tick, asking Buildbucket for the build status
[00:00:31.417] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:31.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:33:0) after 1m0s
[00:01:31.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:33:0)
[00:01:31.908] Timer tick, asking Buildbucket for the build status
[00:01:32.031] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:32.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:34:0) after 1m0s
[00:02:32.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:34:0)
[00:02:32.333] Timer tick, asking Buildbucket for the build status
[00:02:32.416] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:32.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:35:0) after 1m0s
[00:03:32.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:35:0)
[00:03:32.782] Timer tick, asking Buildbucket for the build status
[00:03:32.872] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:32.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:36:0) after 1m0s
[00:04:33.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:36:0)
[00:04:33.514] Timer tick, asking Buildbucket for the build status
[00:04:33.582] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:33.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:37:0) after 1m0s
[00:05:33.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:37:0)
[00:05:33.917] Timer tick, asking Buildbucket for the build status
[00:05:34.013] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:34.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:38:0) after 1m0s
[00:06:34.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:38:0)
[00:06:34.152] Timer tick, asking Buildbucket for the build status
[00:06:34.246] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:34.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:39:0) after 1m0s
[00:07:34.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:39:0)
[00:07:34.374] Timer tick, asking Buildbucket for the build status
[00:07:34.767] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:40:0) after 1m0s
[00:08:34.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:40:0)
[00:08:34.969] Timer tick, asking Buildbucket for the build status
[00:08:35.097] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:35.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:41:0) after 1m0s
[00:09:35.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:41:0)
[00:09:35.171] Timer tick, asking Buildbucket for the build status
[00:09:35.251] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:35.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:42:0) after 1m0s
[00:10:35.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:42:0)
[00:10:35.350] Timer tick, asking Buildbucket for the build status
[00:10:35.434] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:35.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:43:0) after 1m0s
[00:11:35.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:43:0)
[00:11:35.538] Timer tick, asking Buildbucket for the build status
[00:11:35.742] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:35.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:44:0) after 1m0s
[00:12:35.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:44:0)
[00:12:35.875] Timer tick, asking Buildbucket for the build status
[00:12:36.300] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:36.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:45:0) after 1m0s
[00:13:36.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:45:0)
[00:13:36.318] Timer tick, asking Buildbucket for the build status
[00:13:36.595] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:36.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:46:0) after 1m0s
[00:14:36.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:46:0)
[00:14:36.636] Timer tick, asking Buildbucket for the build status
[00:14:36.734] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:36.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:47:0) after 1m0s
[00:15:36.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:47:0)
[00:15:36.803] Timer tick, asking Buildbucket for the build status
[00:15:36.889] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:36.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:48:0) after 1m0s
[00:16:36.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:48:0)
[00:16:36.911] Timer tick, asking Buildbucket for the build status
[00:16:37.018] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:37.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:49:0) after 1m0s
[00:17:37.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:49:0)
[00:17:37.081] Timer tick, asking Buildbucket for the build status
[00:17:37.251] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:37.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:50:0) after 1m0s
[00:18:37.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:50:0)
[00:18:37.346] Timer tick, asking Buildbucket for the build status
[00:18:37.415] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:37.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:51:0) after 1m0s
[00:19:37.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:51:0)
[00:19:37.435] Timer tick, asking Buildbucket for the build status
[00:19:37.663] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:37.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:52:0) after 1m0s
[00:20:37.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:52:0)
[00:20:37.754] Timer tick, asking Buildbucket for the build status
[00:20:37.861] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:37.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:53:0) after 1m0s
[00:21:37.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:53:0)
[00:21:37.926] Timer tick, asking Buildbucket for the build status
[00:21:39.556] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:39.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:54:0) after 1m0s
[00:22:39.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:54:0)
[00:22:39.764] Timer tick, asking Buildbucket for the build status
[00:22:39.854] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:39.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:55:0) after 1m0s
[00:23:40.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:55:0)
[00:23:40.007] Timer tick, asking Buildbucket for the build status
[00:23:40.095] Build 8853557089952878336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:40.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018987152181668080:56:0) after 1m0s
[00:24:47.535] Received PubSub notification, asking Buildbucket for the build status
[00:24:47.612] Build 8853557089952878336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:24:47.612] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614990286459509",
"created_by": "project:chromium",
"created_ts": "1614987016683690",
"id": "8853557089952878336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"76fede2c70a62f81ff7ba138b8d074c0059fb67b\"}}",
"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\": \"76fede2c70a62f81ff7ba138b8d074c0059fb67b\", \"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-31-6mlv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45944, \"got_angle_revision\": \"d96b9ffa5c19f4b92ee0fff918ae5341c7b787d2\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"76fede2c70a62f81ff7ba138b8d074c0059fb67b\", \"got_revision_cp\": \"refs/heads/master@{#860420}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"f6cfc21cf2633296a4f20f14107469c8e00eaec7\", \"got_v8_revision_cp\": \"refs/heads/9.1.54@{#1}\", \"got_webrtc_revision\": \"1413e2db4c9b186176a54e70c52f5b673c2cb939\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33387}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"76fede2c70a62f81ff7ba138b8d074c0059fb67b\", \"swarm_hashes_refs/heads/master(at){#860420}_without_patch\": {\"absl_hardening_tests\": \"42033aee87f14f7caae70e62ce07b154032900b0\", \"accessibility_unittests\": \"1cc4c435ddd26ef8b28bad32a751eb5d5a0deef5\", \"angle_unittests\": \"10810fcd215bf4f9922d692a3131480a7e491600\", \"app_list_unittests\": \"46e614a073993f1189d1e2ebbc29b0cca3a332eb\", \"app_shell_unittests\": \"fc32d4b1b3bd45c81264a361ac070f41ad015b29\", \"ash_unittests\": \"ce14639e8f4999100611b7dc91a27eb8699760de\", \"aura_unittests\": \"61367c46421b3dbd8a2bd4cd7dbc6d75bda53e50\", \"base_unittests\": \"641c07483459677e90b63c157034a60c74ab6806\", \"base_util_unittests\": \"f038265b759aa2a44fb55f4b054878943e935c27\", \"blink_common_unittests\": \"8c22f54d9e6cfe0e29081cde982cb10839e46ebc\", \"blink_fuzzer_unittests\": \"b48ee3f1c1e90349768474d4c5a9d4293d2e2c45\", \"blink_heap_unittests\": \"373e6db09f48866ff53efba49668cc6b04d58707\", \"blink_platform_unittests\": \"5efc889839548f1a16ee010f8c44e6d4155a9bcc\", \"blink_unittests\": \"aa491a3480e711f77b1521fc99f9a74568f31047\", \"boringssl_crypto_tests\": \"04d26bf044b9c9cba6645718c183748dc8917c6a\", \"boringssl_ssl_tests\": \"883a3697a0e11e7f91411a460bed70f7730065e2\", \"browser_tests\": \"713ebcfd4d4499746268c00a0e55abc7d58250b8\", \"capture_unittests\": \"4e6ec5a9c4b39652d34703cde91c918460ca6703\", \"cast_unittests\": \"b1c40856d2ca15f398f01e28b0b85195399da840\", \"cc_unittests\": \"a6bcb008cee0443bbae97f876529fd78c7138a13\", \"chrome_app_unittests\": \"a9e14a4e94ffe23e7d3f6fe6bcd8e868471be389\", \"chromedriver_unittests\": \"4e31d67d5526b76cc8d1692eaf4451e8369c0b09\", \"chromeos_components_unittests\": \"0416535a3795df287a58549ad3098e858829bc9f\", \"chromeos_unittests\": \"59a3fae691d10ac62ddf39e0be7cfba2ef3d65c8\", \"color_unittests\": \"ab840c14b04f19d26480e576ff6187260db70e74\", \"components_browsertests\": \"d74d025149e1ff057c4020b139e089dbdf9cdd24\", \"components_unittests\": \"b88a73132e275760448ec0cd949cc7b7aec1a192\", \"compositor_unittests\": \"0e7fc6e2cf104431f3e5e32f805511414c603a8e\", \"content_browsertests\": \"db6396a232b45738fc783b4f4b866d7292bcee48\", \"content_unittests\": \"cd04378b3a4ea84e2216255c778547f924e1b988\", \"crashpad_tests\": \"af3095318e2ac3116ec37db1fc5210b253cbffa0\", \"crypto_unittests\": \"39f801dfad3f82028b384212eed182d094b979bb\", \"dbus_unittests\": \"557c61afa249290d1caa8d183c2190badb06714f\", \"device_unittests\": \"f0bef7e89710ab685c611b67e72a623f7e5b3057\", \"display_unittests\": \"abb81e7b993ffd16688f945215f0ce3dd8bd09b4\", \"events_unittests\": \"30920bbdb07777767b515203c210d9eaf98790fd\", \"exo_unittests\": \"d72d4fd8305ff6606fa20140a85486010a9863f3\", \"extensions_browsertests\": \"3c6ad892e3a251eac2c877b8cc2bf22c6dc99340\", \"extensions_unittests\": \"b10edb7d962ce8529b2a91929fb68bfc43505310\", \"filesystem_service_unittests\": \"04aa712b4f93a427b7f3187aed405690545ca3d2\", \"gcm_unit_tests\": \"e77eafee647256a182d4d3db58ae2713360e9023\", \"gfx_unittests\": \"a60cf2d57a59a27af66b98ba3da718875a7e50b0\", \"gin_unittests\": \"7865071ef2cd46125d22b22639e5f56db75d39db\", \"gl_unittests_ozone\": \"47067b577aca1c498c48d14ccbcd45f5e9d24694\", \"google_apis_unittests\": \"cb0ad0dc41326b9780d87347edfd1912d2ac1b92\", \"gpu_unittests\": \"8f2e5f098d04d1a01f2095088412cfcc7405b69c\", \"gwp_asan_unittests\": \"5037a2818e47ce12a49ec00ee2837b77f449e5ac\", \"interactive_ui_tests\": \"aee1467a6634166f18fff36e6b208a2ae0c581e5\", \"ipc_tests\": \"526671e855b620836875a6d9fe77f6231ff77563\", \"jingle_unittests\": \"7c7acb7d68c3952f69d0b102bf55af3c49772154\", \"keyboard_unittests\": \"bd830828dd47ec1efa9aaf690a5c1ae1b7ba4746\", \"latency_unittests\": \"677d373dae4ec3b031e00ec2b4fc4a7333d564d0\", \"libjingle_xmpp_unittests\": \"058025b421a779e83a418359c5e4222ed321e233\", \"liburlpattern_unittests\": \"feb9f41ec9af2f92127c0d8cb7dea692021e1e48\", \"media_blink_unittests\": \"8a1c1ad26252ddd1f8e87c080c1f2676cc60c6d2\", \"media_unittests\": \"bf362cade0502459996146a131c7f61d0b2a8f4d\", \"message_center_unittests\": \"2c2c34202b9e6f4ab59254978935b3a697c0e5dd\", \"midi_unittests\": \"63d809856d9f4f0c9914050daa2cc001f6293a9f\", \"mojo_core_unittests\": \"61182564c90cb752840c991d0e53238224a1c3e8\", \"mojo_unittests\": \"d80d7b583e02af8a09ebfab91041d811b40f96b3\", \"nacl_helper_nonsfi_unittests\": \"be286d5c9bf95fd06396cd13dfb57a993fbacb37\", \"nacl_loader_unittests\": \"ad0d52df149b24bafee67e0f8f1034e2dcf2c7a1\", \"native_theme_unittests\": \"14739648656a9fe9dd3cafad76ca6ab4ea1788cd\", \"net_unittests\": \"f161e66b51501d53c149385f097797196e8b7871\", \"ozone_gl_unittests\": \"4e3d8f255b920ccf1ac515648ad56c759e25c755\", \"ozone_unittests\": \"e810e4bfdf571f277f75c1eb6f236f2fb68a2750\", \"ozone_x11_unittests\": \"0429122ca1117a6b19f994a9c60e3d006dc2f7f0\", \"pdf_unittests\": \"606fd00904ec83e8346db15076edbf384cada48e\", \"perfetto_unittests\": \"709797f43a1a18fbffe2649cf4fd0c09984f983d\", \"ppapi_unittests\": \"64522f633e877f0bd12b65699897389f09b8c14e\", \"printing_unittests\": \"93475fc4396fc430e46becc312a829c8fb9b22f3\", \"remoting_unittests\": \"d4cb644c2d37b6d838c6ef1c23e662c8166dbec4\", \"sandbox_linux_unittests\": \"023c06d1e658192614e99797d9d20a0a9aeeeeff\", \"service_manager_unittests\": \"b7383309065f03fd21263fba1a16cfac8bc375f3\", \"services_unittests\": \"8003e8384223468c1740c2ba591e4f83af177b29\", \"shell_dialogs_unittests\": \"d660e1de0d72eddef7df2a020026f036b4c7add1\", \"shell_encryption_unittests\": \"8a970c6110ce7d4908b468486e311d726372cd66\", \"skia_unittests\": \"d23d9270ec822574ec4ae92c42a24dbed7653ef2\", \"snapshot_unittests\": \"63b14d88cda2d01c8eeba1729500dd387f5e3d4c\", \"sql_unittests\": \"147f27658d54072471e57c99ccb2163e5cda7cfd\", \"storage_unittests\": \"69e458ab62d8d809767267ad07c66a273ba4c080\", \"sync_integration_tests\": \"252d92cfc21954ce8670c11f11eb1116aef5f218\", \"traffic_annotation_auditor_unittests\": \"3f15d079a703d871898ff9e0d1a97a504664d73f\", \"ui_base_unittests\": \"9600505d977b243bdad26df4a046e88d42687e03\", \"ui_chromeos_unittests\": \"943812681444470ab25cb78a66a8672c22e706f2\", \"ui_touch_selection_unittests\": \"861ae92c445ebc7de862697a37f7a2594f7dd9c8\", \"unit_tests\": \"fa838931030144788e4ac2731172004f26aa0e60\", \"url_unittests\": \"d40284dcc4f48ffc011e2c73a1048075c611c1b3\", \"usage_time_limit_unittests\": \"bbd91edb9627fdab16fed3397fb62d64ee0baa63\", \"views_unittests\": \"4ed53bfeb05d3d2bb3062a39a971367bffe94952\", \"viz_unittests\": \"c67dbf0946038f9ff086998db4875034a06c2027\", \"wayland_client_perftests\": \"7cefc7ef90ffa27e976e1b9b080825af8acb5e71\", \"wayland_client_tests\": \"3190205806bfd4853e0d7d10ef4d805802db7806\", \"wm_unittests\": \"c6cc0565da9a06e1a21fd96e69c9825feb01dca0\", \"wtf_unittests\": \"19a463a1074a16dcdd9a53ceb3887419d5b9a1c5\", \"zlib_unittests\": \"41f1d8fdbf0390c74e6d2133d19a39292f2a1c8f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_349e20fa0346c22afd9d85eafc166958d65f8d3eb174d2113a3f89c2e822dead_v2\", \"builder_3d5f41e0e2939f2447be43122175fb826239370d7da06e5b6b03ccaa65028999_v2\", \"builder_6012964051d5021e7307eb12ad598b318c657120f21d3654d81218fef56916e6_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_de67dcb387b95c18ea83e54ea320474d260d319089536d63e3279fdbb975ff72_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-31-6mlv\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614987018953811",
"status": "COMPLETED",
"status_changed_ts": "1614990286823826",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45944",
"builder:linux-chromeos-rel",
"buildset:commit/git/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76fede2c70a62f81ff7ba138b8d074c0059fb67b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018987152181668080",
"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/8853557089952878336/+/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:521d8bc5827b1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614990286843490",
"url": "https://ci.chromium.org/b/8853557089952878336",
"utcnow_ts": "1614990287604526"
}
}
[00:24:47.612] Invocation finished in 54m32.438088811s with status SUCCEEDED