[02:15:11.333] New invocation is queued and will start shortly
[02:15:12.517] Starting the invocation (attempt 1)
[02:15:12.558] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:15:12.558] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:15:12.558] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024321996342151152",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9024321996342151152",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master"
]
}
[02:15:14.126] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609899312736853",
"id": "8858891934206803856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609899313868340",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44362",
"builder:linux-chromeos-rel",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024321996342151152",
"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/8858891934206803856/+/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": "1609899313869685",
"url": "https://ci.chromium.org/b/8858891934206803856",
"utcnow_ts": "1609899314096634"
}
}
[02:15:14.126] Task URL: https://ci.chromium.org/b/8858891934206803856
[02:15:14.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:2:0) after 1m0s
[02:15:17.178] Received PubSub notification, asking Buildbucket for the build status
[02:15:17.305] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:2:0)
[02:16:14.196] Timer tick, asking Buildbucket for the build status
[02:16:14.472] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:4:0) after 1m0s
[02:17:14.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:4:0)
[02:17:14.613] Timer tick, asking Buildbucket for the build status
[02:17:14.662] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:14.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:5:0) after 1m0s
[02:18:14.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:5:0)
[02:18:14.799] Timer tick, asking Buildbucket for the build status
[02:18:14.933] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:14.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:6:0) after 1m0s
[02:19:15.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:6:0)
[02:19:15.107] Timer tick, asking Buildbucket for the build status
[02:19:15.272] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:15.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:7:0) after 1m0s
[02:20:15.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:7:0)
[02:20:15.294] Timer tick, asking Buildbucket for the build status
[02:20:15.433] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:15.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:8:0) after 1m0s
[02:21:15.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:8:0)
[02:21:15.560] Timer tick, asking Buildbucket for the build status
[02:21:15.688] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:15.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:9:0) after 1m0s
[02:22:15.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:9:0)
[02:22:15.735] Timer tick, asking Buildbucket for the build status
[02:22:15.901] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:10:0) after 1m0s
[02:23:16.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:10:0)
[02:23:16.155] Timer tick, asking Buildbucket for the build status
[02:23:16.611] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:16.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:11:0) after 1m0s
[02:24:16.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:11:0)
[02:24:16.710] Timer tick, asking Buildbucket for the build status
[02:24:16.816] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:12:0) after 1m0s
[02:25:16.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:12:0)
[02:25:16.897] Timer tick, asking Buildbucket for the build status
[02:25:17.254] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:17.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:13:0) after 1m0s
[02:26:17.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:13:0)
[02:26:17.298] Timer tick, asking Buildbucket for the build status
[02:26:17.375] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:17.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:14:0) after 1m0s
[02:27:17.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:14:0)
[02:27:17.418] Timer tick, asking Buildbucket for the build status
[02:27:17.541] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:17.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:15:0) after 1m0s
[02:28:17.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:15:0)
[02:28:17.596] Timer tick, asking Buildbucket for the build status
[02:28:17.670] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:17.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:16:0) after 1m0s
[02:29:17.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:16:0)
[02:29:17.772] Timer tick, asking Buildbucket for the build status
[02:29:18.607] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:18.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:17:0) after 1m0s
[02:30:18.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:17:0)
[02:30:18.712] Timer tick, asking Buildbucket for the build status
[02:30:19.427] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:19.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:18:0) after 1m0s
[02:31:19.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:18:0)
[02:31:19.518] Timer tick, asking Buildbucket for the build status
[02:31:19.637] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:19.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:19:0) after 1m0s
[02:32:19.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:19:0)
[02:32:19.778] Timer tick, asking Buildbucket for the build status
[02:32:19.890] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:19.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:20:0) after 1m0s
[02:33:19.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:20:0)
[02:33:19.965] Timer tick, asking Buildbucket for the build status
[02:33:20.159] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:20.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:21:0) after 1m0s
[02:34:20.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:21:0)
[02:34:20.157] Timer tick, asking Buildbucket for the build status
[02:34:20.292] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:20.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:22:0) after 1m0s
[02:35:20.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:22:0)
[02:35:20.380] Timer tick, asking Buildbucket for the build status
[02:35:20.504] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:20.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:23:0) after 1m0s
[02:36:20.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:23:0)
[02:36:20.550] Timer tick, asking Buildbucket for the build status
[02:36:20.667] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:24:0) after 1m0s
[02:37:20.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:24:0)
[02:37:20.714] Timer tick, asking Buildbucket for the build status
[02:37:20.876] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:20.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:25:0) after 1m0s
[02:38:20.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:25:0)
[02:38:20.884] Timer tick, asking Buildbucket for the build status
[02:38:20.965] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:20.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:26:0) after 1m0s
[02:39:20.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:26:0)
[02:39:20.985] Timer tick, asking Buildbucket for the build status
[02:39:21.101] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:21.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:27:0) after 1m0s
[02:40:21.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:27:0)
[02:40:21.172] Timer tick, asking Buildbucket for the build status
[02:40:21.287] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:21.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:28:0) after 1m0s
[02:41:21.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:28:0)
[02:41:21.321] Timer tick, asking Buildbucket for the build status
[02:41:21.695] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:21.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:29:0) after 1m0s
[02:42:21.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:29:0)
[02:42:21.724] Timer tick, asking Buildbucket for the build status
[02:42:21.774] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:21.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:30:0) after 1m0s
[02:43:21.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:30:0)
[02:43:21.791] Timer tick, asking Buildbucket for the build status
[02:43:21.869] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:21.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:31:0) after 1m0s
[02:44:21.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:31:0)
[02:44:21.937] Timer tick, asking Buildbucket for the build status
[02:44:22.025] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:22.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:32:0) after 1m0s
[02:45:22.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:32:0)
[02:45:22.073] Timer tick, asking Buildbucket for the build status
[02:45:22.300] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:22.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:33:0) after 1m0s
[02:46:22.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:33:0)
[02:46:22.367] Timer tick, asking Buildbucket for the build status
[02:46:22.534] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:22.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:34:0) after 1m0s
[02:47:22.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:34:0)
[02:47:22.557] Timer tick, asking Buildbucket for the build status
[02:47:22.884] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:22.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:35:0) after 1m0s
[02:48:22.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:35:0)
[02:48:22.882] Timer tick, asking Buildbucket for the build status
[02:48:22.993] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:22.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:36:0) after 1m0s
[02:49:23.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:36:0)
[02:49:23.044] Timer tick, asking Buildbucket for the build status
[02:49:23.198] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:23.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:37:0) after 1m0s
[02:50:23.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:37:0)
[02:50:23.218] Timer tick, asking Buildbucket for the build status
[02:50:23.383] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:23.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:38:0) after 1m0s
[02:51:23.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:38:0)
[02:51:23.427] Timer tick, asking Buildbucket for the build status
[02:51:23.650] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:23.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:39:0) after 1m0s
[02:52:23.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:39:0)
[02:52:23.671] Timer tick, asking Buildbucket for the build status
[02:52:23.899] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:23.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:40:0) after 1m0s
[02:53:23.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:40:0)
[02:53:23.946] Timer tick, asking Buildbucket for the build status
[02:53:24.608] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:24.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:41:0) after 1m0s
[02:54:24.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:41:0)
[02:54:24.626] Timer tick, asking Buildbucket for the build status
[02:54:25.133] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:42:0) after 1m0s
[02:55:25.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:42:0)
[02:55:25.155] Timer tick, asking Buildbucket for the build status
[02:55:25.267] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:43:0) after 1m0s
[02:56:25.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:43:0)
[02:56:25.318] Timer tick, asking Buildbucket for the build status
[02:56:25.794] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:25.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:44:0) after 1m0s
[02:57:25.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:44:0)
[02:57:25.818] Timer tick, asking Buildbucket for the build status
[02:57:26.346] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:26.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:45:0) after 1m0s
[02:58:26.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:45:0)
[02:58:26.384] Timer tick, asking Buildbucket for the build status
[02:58:26.564] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:46:0) after 1m0s
[02:59:26.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:46:0)
[02:59:26.656] Timer tick, asking Buildbucket for the build status
[02:59:26.838] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:47:0) after 1m0s
[03:00:26.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:47:0)
[03:00:26.877] Timer tick, asking Buildbucket for the build status
[03:00:27.348] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:27.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:48:0) after 1m0s
[03:01:27.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:48:0)
[03:01:27.373] Timer tick, asking Buildbucket for the build status
[03:01:27.513] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:27.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:49:0) after 1m0s
[03:02:27.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:49:0)
[03:02:27.570] Timer tick, asking Buildbucket for the build status
[03:02:27.700] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:27.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:50:0) after 1m0s
[03:03:27.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:50:0)
[03:03:27.729] Timer tick, asking Buildbucket for the build status
[03:03:27.875] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:27.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:51:0) after 1m0s
[03:04:27.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:51:0)
[03:04:27.898] Timer tick, asking Buildbucket for the build status
[03:04:28.068] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:28.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:52:0) after 1m0s
[03:05:28.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:52:0)
[03:05:28.098] Timer tick, asking Buildbucket for the build status
[03:05:28.292] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:28.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:53:0) after 1m0s
[03:06:28.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:53:0)
[03:06:28.333] Timer tick, asking Buildbucket for the build status
[03:06:28.541] Build 8858891934206803856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:28.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024321996342151152:54:0) after 1m0s
[03:07:07.858] Received PubSub notification, asking Buildbucket for the build status
[03:07:07.959] Build 8858891934206803856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:07:07.959] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609902425306680",
"created_by": "project:chromium",
"created_ts": "1609899312736853",
"id": "8858891934206803856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"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\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"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-30-hvdv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44362, \"got_angle_revision\": \"e91097bf3a24895f23b928ed12087dcbb1d54d7b\", \"got_dawn_revision\": \"200941c797512895039e9f0315fc18fd9d9734ad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"got_revision_cp\": \"refs/heads/master@{#840444}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"c8d1d1cbceae8f59975b2ec7d86981ec3fabb11b\", \"got_v8_revision_cp\": \"refs/heads/8.9.212@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"swarm_hashes_refs/heads/master(at){#840444}_without_patch\": {\"absl_hardening_tests\": \"5c205f9be0615a77b1f51bc8b65c4c489f311f24\", \"accessibility_unittests\": \"7a68be990ca674dc0247113832bb05be562ba8b6\", \"angle_unittests\": \"5c256b6f058fa23f55f87e33f10684f32bc8415d\", \"app_list_unittests\": \"9e5fc563b79dd3f865ee2d74b67bd1f8430cabab\", \"app_shell_unittests\": \"68292a74f35dc5f51bbf807e6e9302393efd9fa8\", \"ash_unittests\": \"82a261cdd76f86b4900998952c9748440ffd5a07\", \"aura_unittests\": \"2e3ff1940728f71529de0ec7405a9078e7ace096\", \"base_unittests\": \"5603f525631e81f50e19a3807e024c1382cf16ae\", \"base_util_unittests\": \"d32e8d4744d9d6a2bb65c5393699c03a183a4438\", \"blink_common_unittests\": \"2af9aa9d505d4f5a434b0b015804d8d1d7f8f682\", \"blink_fuzzer_unittests\": \"9e41742511278c97effd1d35f148998547d36157\", \"blink_heap_unittests\": \"dcf80670c2f9c9a0f61de16290d01781dd1eb07d\", \"blink_platform_unittests\": \"513ab0e1472ed3d8583789cfeffdc327e148954b\", \"blink_unittests\": \"2b61f1b532e6cda951909d1498fc38ae76b83000\", \"boringssl_crypto_tests\": \"71721f0cce3b5960b4ff3a71bc9252e38ea29228\", \"boringssl_ssl_tests\": \"d8f1232826915469dd21c6be866d0a95569f6441\", \"browser_tests\": \"45d9bf531698a32bfb00d6a062c6d408e674857f\", \"capture_unittests\": \"5bce74d43aaaf883fc5c2d35bd51725f183362cd\", \"cast_unittests\": \"4ab62dd60696f3be1db04df32b55995eea3631df\", \"cc_unittests\": \"89bf4254fbd05abe7cd4ab0f726ede3e73cbaa4f\", \"chrome_app_unittests\": \"d281055e3f03b7e2b3c315c14539339b5903165b\", \"chromedriver_unittests\": \"e8c4d5d2898b8eab9f5a605b5f571cf059ffa3c4\", \"chromeos_components_unittests\": \"d6d051e75430ae8ee76a0197f90c1e9e1b1ab0e3\", \"chromeos_unittests\": \"aed2b1a01cad13799d0258312828dc461b3a1aa2\", \"components_browsertests\": \"037bd0344b407e01d844acd0e72a1d41f1fe4c39\", \"components_unittests\": \"5584c46a4f14006aad1d35f8437ae5ceaea0bbc9\", \"compositor_unittests\": \"a06c82b889df17fd8cdf82fa50d8f54001c6873f\", \"content_browsertests\": \"7f0556d5af1ba3d815e4fd0d9aad070a5ea55a79\", \"content_unittests\": \"5265b2424be1ff76aa471e24f933b7bae2860cad\", \"crashpad_tests\": \"f27603a6abc653fd2690dfe4b0915491dd424be2\", \"crypto_unittests\": \"9f19d87a625ceab937780f1ca5541f011776c295\", \"dbus_unittests\": \"9b80f6793b43f3867d68c197b932165b259ca8b9\", \"device_unittests\": \"3dab3b7523e75622fdf75883ba9f1b5363226452\", \"display_unittests\": \"8ca433546936e2949194cffbe49209031cab7763\", \"events_unittests\": \"9e688877179088560821cadc181c7929a3b7bfef\", \"exo_unittests\": \"1844fa611e6c76d0ab429c002b24c9d8a00d5129\", \"extensions_browsertests\": \"5c8764a8fff9ad1b20adc48dd47f02401d323795\", \"extensions_unittests\": \"dc7bb4f829b91336d3d0fde0ac2dd6fcfbac3db1\", \"filesystem_service_unittests\": \"b84a4a14bc2691c32c62d957227f5926f9f57be0\", \"gcm_unit_tests\": \"cdcc07b0beb3a883ea6ae9aab4b312d904d7ade0\", \"gfx_unittests\": \"a2613e179fe4964f67c7339d3b442bf51b7f982a\", \"gin_unittests\": \"9b3563b0e04a79b1a90e5b6db787e1b4276731bc\", \"gl_unittests_ozone\": \"ed0560318adcbd06840f751f56d264cdfe0fcffd\", \"google_apis_unittests\": \"ead08596a85bb8d7928ab152e8458011fb8898dd\", \"gpu_unittests\": \"56c5e01a740832767879d0314cac61aac1c4194e\", \"gwp_asan_unittests\": \"f7d76d5a579d16b057b7c8f49ca78a845eaf46ec\", \"interactive_ui_tests\": \"07692ae5f7295bcf65f68941963deb05e8cc33fb\", \"ipc_tests\": \"1e972685bb3a6ccc058871e60e5fd072d679b664\", \"jingle_unittests\": \"f49dcc5d6748c3c7b735e793eea1553300b41806\", \"keyboard_unittests\": \"5f17b539578420af0d8b8b39d90ba8eac57ca306\", \"latency_unittests\": \"e4e54a632d4fc9f6b82e1722e5912ea313531bda\", \"libjingle_xmpp_unittests\": \"043e185cc09d126d5a046c0a18c7e2bda052f1a0\", \"liburlpattern_unittests\": \"bd069934601f87ca1faee4100cb93153532a3466\", \"media_blink_unittests\": \"9122c000e2a1840ede941839612b88b10d3b0b7d\", \"media_unittests\": \"bc8f415293d37e7725017db93341d9efe21cd86d\", \"message_center_unittests\": \"0073bb5b5773e0e54a6a3fd1a055d231b9f6b174\", \"midi_unittests\": \"1ee870779e7067ae72ee6d71b0ae9b4721f53a01\", \"mojo_core_unittests\": \"76b1f8a077ae8efed14d0e45d61cf8fbc73f66a8\", \"mojo_unittests\": \"50799a94590f9cb8fc63c43a913af4fe8483dad7\", \"nacl_helper_nonsfi_unittests\": \"c3a1e768607f552bd238fc86b34127dbe73c1d7d\", \"nacl_loader_unittests\": \"4af4603a2001339af60805622c9eacfdcf00ac40\", \"native_theme_unittests\": \"151365496bddc3adced447509f7578c3c719daf7\", \"net_unittests\": \"1a8c620306b4d92330685a7a957f424e333f139d\", \"ozone_gl_unittests\": \"5538eadca53d892aa6a0b105a9b49b6510f86cdf\", \"ozone_unittests\": \"edb4b9213d1e47e8a4ff66305d68a837814ff45f\", \"ozone_x11_unittests\": \"c185c08a5871cb982c9e24496d127448add514ef\", \"pdf_unittests\": \"889ecfbae012a1de234a5d71826a75b245a6c47b\", \"perfetto_unittests\": \"dbd9e1842a7a54b6f52e6ced2b6c1d19d279589c\", \"ppapi_unittests\": \"fa9c986d8e7aaf5f77a899a2b2fdae8c2de9d881\", \"printing_unittests\": \"8470c9dd597d32077887668eecebacb1896550de\", \"remoting_unittests\": \"09dd9f0b33083cdd7385b36c384f24e3c6a5af2d\", \"sandbox_linux_unittests\": \"02e7b3fa3fd9b6279e104a7540e4815837c76469\", \"service_manager_unittests\": \"6f3002f71c6cb16bbf3c8247fdb51ff5a9d6dd6d\", \"services_unittests\": \"87a3c7841aae44167bc760c7071fd9eec5254212\", \"shell_dialogs_unittests\": \"6d10e2a44b06f49f45628383614363e4f897dd65\", \"shell_encryption_unittests\": \"43cf0baebf8e7e5132384372cdeb78da50de48ce\", \"skia_unittests\": \"7dfce749c826a1f1c8b74bed1275d08fc6cbac40\", \"snapshot_unittests\": \"08a0f49123f88aec099b34d38855053c71e737a5\", \"sql_unittests\": \"2b58b91083b5e3f00588dadd2055a8234ee643d2\", \"storage_unittests\": \"3e31be282776aafea66055a578abec9914886e67\", \"sync_integration_tests\": \"fb26fff6c7d9e3180b7a8738d06275cc5b58c838\", \"traffic_annotation_auditor_unittests\": \"8df66911194b717f32b80fbe327eec5c0d2316ff\", \"ui_base_unittests\": \"96dffc4bf141fb020f7c5f3814939abc103c3f03\", \"ui_chromeos_unittests\": \"4f87dd38acd7703ec42ac1c33e7da5e135f5b3c5\", \"ui_touch_selection_unittests\": \"57cfb54be54a1b20c14c6c6e9e4be7cad6bef2ef\", \"unit_tests\": \"806f7cfcbcd0b33cd053f9dbf77d9a4f6127f3a4\", \"url_unittests\": \"a8c865d276153127cead6bbae6249d2d169bca9f\", \"usage_time_limit_unittests\": \"a36db6bb0f0d5eef845901083c9a1ebb37203985\", \"views_unittests\": \"ca1d68f43e6ce9495ac931f7c98eef4923a6edbd\", \"viz_unittests\": \"2483cc9f9aa4fb37bc5accecb5841d58b132293c\", \"wayland_client_perftests\": \"2e44a6f6fe20b8270d257d06c9f3f40ee88e432d\", \"wm_unittests\": \"9c48e33e536c3030cac467fc9d25971856de898f\", \"wtf_unittests\": \"2fe994c41b95b6fabdc295ef7250e2df51cba774\", \"zlib_unittests\": \"e523607ab2c0094bae752dc3122facddfffb96e0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_21a338e382a0dbd787bea981107dd7bac336a437920c3cc1b87ea8999b8bcb15_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_78080097a3960c8831291638a631c67e272aa3d821d469cd7fbf44d1ce982b94_v2\", \"builder_79262342f920534415b6d515c76557b8e5c65ba3d1c1b85ae86a8176a9056a22_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_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-30-hvdv\"], \"image\": [\"chrome-xenial-20122700-a13233ce10d\"], \"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": "1609899316324335",
"status": "COMPLETED",
"status_changed_ts": "1609902425714504",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44362",
"builder:linux-chromeos-rel",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024321996342151152",
"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/8858891934206803856/+/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:50ee4b94473d0410",
"user_agent:luci-scheduler"
],
"updated_ts": "1609902425737990",
"url": "https://ci.chromium.org/b/8858891934206803856",
"utcnow_ts": "1609902427944324"
}
}
[03:07:07.959] Invocation finished in 51m56.642475127s with status SUCCEEDED