[21:52:25.005] New invocation is queued and will start shortly
[21:52:26.313] Starting the invocation (attempt 1)
[21:52:26.411] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:52:26.414] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:52:26.414] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022979574037070032",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4f3c7d413de9308c936a7a394d74f2122764e779\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022979574037070032",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4f3c7d413de9308c936a7a394d74f2122764e779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f3c7d413de9308c936a7a394d74f2122764e779",
"gitiles_ref:refs/heads/master"
]
}
[21:52:27.388] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611179546514895",
"id": "8857549511793035728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f3c7d413de9308c936a7a394d74f2122764e779\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611179547146062",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44775",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f3c7d413de9308c936a7a394d74f2122764e779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f3c7d413de9308c936a7a394d74f2122764e779",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022979574037070032",
"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/8857549511793035728/+/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": "1611179547146170",
"url": "https://ci.chromium.org/b/8857549511793035728",
"utcnow_ts": "1611179547371727"
}
}
[21:52:27.388] Task URL: https://ci.chromium.org/b/8857549511793035728
[21:52:27.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:2:0) after 1m0s
[21:52:30.544] Received PubSub notification, asking Buildbucket for the build status
[21:52:30.620] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:27.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:2:0)
[21:53:27.467] Timer tick, asking Buildbucket for the build status
[21:53:27.884] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:27.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:4:0) after 1m0s
[21:54:27.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:4:0)
[21:54:27.904] Timer tick, asking Buildbucket for the build status
[21:54:27.971] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:27.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:5:0) after 1m0s
[21:55:28.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:5:0)
[21:55:28.044] Timer tick, asking Buildbucket for the build status
[21:55:28.326] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:28.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:6:0) after 1m0s
[21:56:28.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:6:0)
[21:56:28.351] Timer tick, asking Buildbucket for the build status
[21:56:28.725] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:28.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:7:0) after 1m0s
[21:57:28.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:7:0)
[21:57:28.915] Timer tick, asking Buildbucket for the build status
[21:57:29.356] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:29.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:8:0) after 1m0s
[21:58:29.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:8:0)
[21:58:29.491] Timer tick, asking Buildbucket for the build status
[21:58:29.831] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:29.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:9:0) after 1m0s
[21:59:29.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:9:0)
[21:59:29.935] Timer tick, asking Buildbucket for the build status
[21:59:30.069] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:30.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:10:0) after 1m0s
[22:00:30.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:10:0)
[22:00:30.116] Timer tick, asking Buildbucket for the build status
[22:00:30.622] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:30.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:11:0) after 1m0s
[22:01:30.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:11:0)
[22:01:30.689] Timer tick, asking Buildbucket for the build status
[22:01:30.924] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:30.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:12:0) after 1m0s
[22:02:30.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:12:0)
[22:02:30.910] Timer tick, asking Buildbucket for the build status
[22:02:31.035] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:31.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:13:0) after 1m0s
[22:03:31.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:13:0)
[22:03:31.107] Timer tick, asking Buildbucket for the build status
[22:03:31.178] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:31.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:14:0) after 1m0s
[22:04:31.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:14:0)
[22:04:31.227] Timer tick, asking Buildbucket for the build status
[22:04:31.291] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:31.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:15:0) after 1m0s
[22:05:31.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:15:0)
[22:05:31.313] Timer tick, asking Buildbucket for the build status
[22:05:31.382] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:31.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:16:0) after 1m0s
[22:06:31.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:16:0)
[22:06:31.424] Timer tick, asking Buildbucket for the build status
[22:06:31.736] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:31.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:17:0) after 1m0s
[22:07:31.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:17:0)
[22:07:31.817] Timer tick, asking Buildbucket for the build status
[22:07:31.992] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:31.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:18:0) after 1m0s
[22:08:32.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:18:0)
[22:08:32.113] Timer tick, asking Buildbucket for the build status
[22:08:32.444] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:32.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:19:0) after 1m0s
[22:09:32.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:19:0)
[22:09:32.505] Timer tick, asking Buildbucket for the build status
[22:09:32.665] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:32.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:20:0) after 1m0s
[22:10:32.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:20:0)
[22:10:32.690] Timer tick, asking Buildbucket for the build status
[22:10:32.778] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:32.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:21:0) after 1m0s
[22:11:32.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:21:0)
[22:11:32.803] Timer tick, asking Buildbucket for the build status
[22:11:32.876] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:32.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:22:0) after 1m0s
[22:12:32.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:22:0)
[22:12:32.943] Timer tick, asking Buildbucket for the build status
[22:12:33.060] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:33.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:23:0) after 1m0s
[22:13:33.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:23:0)
[22:13:33.122] Timer tick, asking Buildbucket for the build status
[22:13:33.708] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:33.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:24:0) after 1m0s
[22:14:33.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:24:0)
[22:14:33.760] Timer tick, asking Buildbucket for the build status
[22:14:33.843] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:33.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:25:0) after 1m0s
[22:15:33.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:25:0)
[22:15:33.908] Timer tick, asking Buildbucket for the build status
[22:15:33.977] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:33.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:26:0) after 1m0s
[22:16:34.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:26:0)
[22:16:34.023] Timer tick, asking Buildbucket for the build status
[22:16:34.078] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:34.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:27:0) after 1m0s
[22:17:34.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:27:0)
[22:17:34.103] Timer tick, asking Buildbucket for the build status
[22:17:34.213] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:34.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:28:0) after 1m0s
[22:18:34.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:28:0)
[22:18:34.278] Timer tick, asking Buildbucket for the build status
[22:18:34.373] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:34.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:29:0) after 1m0s
[22:19:34.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:29:0)
[22:19:34.403] Timer tick, asking Buildbucket for the build status
[22:19:34.456] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:34.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:30:0) after 1m0s
[22:20:34.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:30:0)
[22:20:34.562] Timer tick, asking Buildbucket for the build status
[22:20:34.920] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:34.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:31:0) after 1m0s
[22:21:35.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:31:0)
[22:21:35.073] Timer tick, asking Buildbucket for the build status
[22:21:35.147] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:35.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:32:0) after 1m0s
[22:22:35.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:32:0)
[22:22:35.260] Timer tick, asking Buildbucket for the build status
[22:22:35.371] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:35.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:33:0) after 1m0s
[22:23:35.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:33:0)
[22:23:35.430] Timer tick, asking Buildbucket for the build status
[22:23:35.505] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:35.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:34:0) after 1m0s
[22:24:35.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:34:0)
[22:24:35.618] Timer tick, asking Buildbucket for the build status
[22:24:35.694] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:35.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:35:0) after 1m0s
[22:25:35.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:35:0)
[22:25:35.773] Timer tick, asking Buildbucket for the build status
[22:25:35.850] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:35.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:36:0) after 1m0s
[22:26:35.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:36:0)
[22:26:35.879] Timer tick, asking Buildbucket for the build status
[22:26:36.105] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:36.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:37:0) after 1m0s
[22:27:36.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:37:0)
[22:27:36.185] Timer tick, asking Buildbucket for the build status
[22:27:36.612] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:36.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:38:0) after 1m0s
[22:28:36.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:38:0)
[22:28:36.667] Timer tick, asking Buildbucket for the build status
[22:28:36.879] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:36.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:39:0) after 1m0s
[22:29:37.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:39:0)
[22:29:37.068] Timer tick, asking Buildbucket for the build status
[22:29:37.131] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:37.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:40:0) after 1m0s
[22:30:37.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:40:0)
[22:30:37.185] Timer tick, asking Buildbucket for the build status
[22:30:37.418] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:37.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:41:0) after 1m0s
[22:31:37.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:41:0)
[22:31:37.548] Timer tick, asking Buildbucket for the build status
[22:31:37.621] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:37.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:42:0) after 1m0s
[22:32:37.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:42:0)
[22:32:37.676] Timer tick, asking Buildbucket for the build status
[22:32:38.030] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:38.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:43:0) after 1m0s
[22:33:38.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:43:0)
[22:33:38.089] Timer tick, asking Buildbucket for the build status
[22:33:38.195] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:38.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:44:0) after 1m0s
[22:34:38.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:44:0)
[22:34:38.225] Timer tick, asking Buildbucket for the build status
[22:34:38.607] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:38.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:45:0) after 1m0s
[22:35:38.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:45:0)
[22:35:38.728] Timer tick, asking Buildbucket for the build status
[22:35:38.831] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:38.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:46:0) after 1m0s
[22:36:38.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:46:0)
[22:36:38.904] Timer tick, asking Buildbucket for the build status
[22:36:39.031] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:39.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:47:0) after 1m0s
[22:37:39.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:47:0)
[22:37:39.099] Timer tick, asking Buildbucket for the build status
[22:37:39.184] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:39.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:48:0) after 1m0s
[22:38:39.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:48:0)
[22:38:39.332] Timer tick, asking Buildbucket for the build status
[22:38:39.466] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:39.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:49:0) after 1m0s
[22:39:39.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:49:0)
[22:39:39.556] Timer tick, asking Buildbucket for the build status
[22:39:39.659] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:39.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:50:0) after 1m0s
[22:40:39.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:50:0)
[22:40:39.714] Timer tick, asking Buildbucket for the build status
[22:40:39.829] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:39.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:51:0) after 1m0s
[22:41:39.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:51:0)
[22:41:39.872] Timer tick, asking Buildbucket for the build status
[22:41:40.513] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:40.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:52:0) after 1m0s
[22:42:40.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:52:0)
[22:42:40.532] Timer tick, asking Buildbucket for the build status
[22:42:40.683] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:40.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:53:0) after 1m0s
[22:43:40.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:53:0)
[22:43:40.706] Timer tick, asking Buildbucket for the build status
[22:43:40.884] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:40.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:54:0) after 1m0s
[22:44:40.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:54:0)
[22:44:40.894] Timer tick, asking Buildbucket for the build status
[22:44:41.073] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:41.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:55:0) after 1m0s
[22:45:41.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:55:0)
[22:45:41.118] Timer tick, asking Buildbucket for the build status
[22:45:41.267] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:41.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:56:0) after 1m0s
[22:46:41.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:56:0)
[22:46:41.262] Timer tick, asking Buildbucket for the build status
[22:46:41.383] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:41.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:57:0) after 1m0s
[22:47:41.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:57:0)
[22:47:41.432] Timer tick, asking Buildbucket for the build status
[22:47:41.603] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:41.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:58:0) after 1m0s
[22:48:41.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:58:0)
[22:48:41.653] Timer tick, asking Buildbucket for the build status
[22:48:41.774] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:59:0) after 1m0s
[22:49:41.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:59:0)
[22:49:41.852] Timer tick, asking Buildbucket for the build status
[22:49:41.953] Build 8857549511793035728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:41.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022979574037070032:60:0) after 1m0s
[22:50:10.355] Received PubSub notification, asking Buildbucket for the build status
[22:50:10.493] Build 8857549511793035728: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:50:10.493] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611183008822345",
"created_by": "project:chromium",
"created_ts": "1611179546514895",
"failure_reason": "BUILD_FAILURE",
"id": "8857549511793035728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f3c7d413de9308c936a7a394d74f2122764e779\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ExternalProviderImplChromeOSTest.PriorityCompleted\"}, \"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\": \"4f3c7d413de9308c936a7a394d74f2122764e779\", \"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-47-zair\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44775, \"got_angle_revision\": \"ea71c6b63417af438afaf26a886b03621d85f14f\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"4f3c7d413de9308c936a7a394d74f2122764e779\", \"got_revision_cp\": \"refs/heads/master@{#845349}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"812c73cdc25ade1c08750873f0c1e8f8def206dc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33043}\", \"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\": \"4f3c7d413de9308c936a7a394d74f2122764e779\", \"swarm_hashes_refs/heads/master(at){#845349}_without_patch\": {\"absl_hardening_tests\": \"39a9ad978d1ee8583c55041a581691ed9e6b7a48\", \"accessibility_unittests\": \"db51c95fb648ed0edb8d0f7fd1a48749e93244d5\", \"angle_unittests\": \"6a8d7868e63c77f591222b80ac39d416dbbf86dc\", \"app_list_unittests\": \"45ac370efa44b731760aabe6ea74e8d1397674b4\", \"app_shell_unittests\": \"003de63d76d4ca8f35898d169e6ddc506b9c81dc\", \"ash_unittests\": \"40eb677b36876f3476dc315e618763704b99e205\", \"aura_unittests\": \"aec0bfceba208a5dfbf17b401b0075e4c60ea800\", \"base_unittests\": \"de43855c35be6eea328376d603c6be456cdeb32f\", \"base_util_unittests\": \"244330cf4f0460d70ebd4a5c17139987b8018a1d\", \"blink_common_unittests\": \"7d0b2606e75c3370f809613e8d87e35d4d061f70\", \"blink_fuzzer_unittests\": \"49553f6c15ac2c75b9fe93110d567c359cb4ff41\", \"blink_heap_unittests\": \"bf52707b778e8c286b3b7a4f516bfbf7494367b5\", \"blink_platform_unittests\": \"9c040237e55e9a4faf66d2094d69fd54ff341679\", \"blink_unittests\": \"320fb4f7560113e980af479c0ee047c61359086c\", \"boringssl_crypto_tests\": \"23e71fe9df9b8501f2752a0243736db6fb60deb9\", \"boringssl_ssl_tests\": \"051e74b38d79dbb5b2f659552773ee24864f0dd5\", \"browser_tests\": \"c352de20c8c7442d355d2d251a24965dbd93d394\", \"capture_unittests\": \"cc73180f4acf063bbbe04a3ada551cc9a3782b57\", \"cast_unittests\": \"f313c6b62ed992ace97c0e317848f223bc67eb7f\", \"cc_unittests\": \"f851323a0710af60987fd9a7101deeac0da35162\", \"chrome_app_unittests\": \"b1e6e505a6cfbc092b92fe6f2d525f5aacb5fe41\", \"chromedriver_unittests\": \"1e3684b77d0e1521c76339c2197bdb0eaeb4c72b\", \"chromeos_components_unittests\": \"8e64921010fd73f11b82bb294dfd08a01c29ce86\", \"chromeos_unittests\": \"35c9a9b5461d533a6bd28e20114b3cb0c241cd19\", \"components_browsertests\": \"df14aed0f07effe69ceb815e3a4a5c8320de2754\", \"components_unittests\": \"d904c75163717ec5939aeb9c0d16fcafc4548e85\", \"compositor_unittests\": \"212b32ed9959fdd1f391108a740ee6b2f958a4e4\", \"content_browsertests\": \"2574bb78e06d7d47690bc36600a432f782518225\", \"content_unittests\": \"a063a2ed7bb457cbcd8eb868a0a6d4710c27d4cd\", \"crashpad_tests\": \"f243ea2e851509b852e45c81695dd8ba7da53b56\", \"crypto_unittests\": \"728a70a8e91f911cdda7361f067b8e3aa4678986\", \"dbus_unittests\": \"d9235e0c1bb5bd6e96a6668fc886c69f5530674e\", \"device_unittests\": \"fe3409e6616ef47957d5bba13fbd5032f3265bee\", \"display_unittests\": \"a8be936df1f9b75b79008301c76b3d1a4ebef3e6\", \"events_unittests\": \"469106f0cc58aa2de0e951936c5e5af7feaa91ea\", \"exo_unittests\": \"9483068fddb531174455b532cb9ee60527a5a57c\", \"extensions_browsertests\": \"32af63fbf60dcbe9b2fd674f53b587916357ce8a\", \"extensions_unittests\": \"0020bc3a53d0f2194b14848c021191285f79ece3\", \"filesystem_service_unittests\": \"98b7ac2be6606684f98cb829094c5f1c7a434768\", \"gcm_unit_tests\": \"8e6b898f9a54bc1beaa3a2c7aba0ac0fd897fd12\", \"gfx_unittests\": \"1f15cada13e783def1b448bccca803b7961b07da\", \"gin_unittests\": \"555ada4edba78533d0da242e6101cdd26dfd8f93\", \"gl_unittests_ozone\": \"27080aa091da91b78e22883bdf3e255cee7cd791\", \"google_apis_unittests\": \"1dbd734224a873f1cc3447e609d77a3e4b2f465a\", \"gpu_unittests\": \"27eb6809ace89a23750017c17b1ddd862560f807\", \"gwp_asan_unittests\": \"822cc5a7d1e79601c087333ac76bcdf37029a2b0\", \"interactive_ui_tests\": \"5ae740836bcaf1884aa1b2f4de0bd9f38bb300eb\", \"ipc_tests\": \"0bcf942c383a4d41d2040159c95b329426a582bd\", \"jingle_unittests\": \"b960e3ede9d264b17c755bf544d3c38228116265\", \"keyboard_unittests\": \"213ef090540b764777b033926ed750ab8ad8d405\", \"latency_unittests\": \"d9eb48b4ce01414c789313da9aff211725656655\", \"libjingle_xmpp_unittests\": \"1ce110ed923a0b9f064317b60950dee215509f09\", \"liburlpattern_unittests\": \"8c58b74104dc019917bde000cd40310954a87629\", \"media_blink_unittests\": \"1c2a6ee56b7e27fd030c419078d267f806a6dd7a\", \"media_unittests\": \"05a8853400bb093943d23539234e80c4ed004c8c\", \"message_center_unittests\": \"9ee630bc1213ea283d6cd35eb617ace2d689854d\", \"midi_unittests\": \"044586b2e0549e56e0fbdcf791d099db578a9aec\", \"mojo_core_unittests\": \"5699b902e3eaaa310a0cfcabecc35f545de76c9f\", \"mojo_unittests\": \"9d623786cae7f5413a6191ea439c19d955976d84\", \"nacl_helper_nonsfi_unittests\": \"193e237b4987101df9864480ff82fb6daf77dd28\", \"nacl_loader_unittests\": \"4820ba94eb5a507b3e96150bd6d65dfcc440ada2\", \"native_theme_unittests\": \"26a197083b851edddd3a71ce6c88a66a5b96305f\", \"net_unittests\": \"9e7ce1a89bd214afe63e4fb1148dd53bfb9a412b\", \"ozone_gl_unittests\": \"ade56ee060ad13836487efd1339f82213437386b\", \"ozone_unittests\": \"748b64e853e0189f57f2c25f882785422c6bfa90\", \"ozone_x11_unittests\": \"cea3a372095d9fa617cbd0d5811cf9e0d0e091c6\", \"pdf_unittests\": \"cbf72190c3518819c4518058d46970a4e6a1e3a1\", \"perfetto_unittests\": \"a775cf6e89377a58e304b2ff6b4b8c3f0e3db803\", \"ppapi_unittests\": \"97fa62fb2db7b1dafa061110b6361a193d3bb35b\", \"printing_unittests\": \"18adab66949a82f61b383ee3fd99c4c87097501d\", \"remoting_unittests\": \"ee23741866685f46c7142cd7dd7da4e749569565\", \"sandbox_linux_unittests\": \"f01291b519d1f5788356ecbc160e702bb84a8e37\", \"service_manager_unittests\": \"914be5f61a2bf4de1077a0a8fb6cd1329744d67f\", \"services_unittests\": \"6c5c40b40f216604ab14a35dbe5889afa0cbd284\", \"shell_dialogs_unittests\": \"506bac7ce96cf6825cc9342e841f924b26596b19\", \"shell_encryption_unittests\": \"98692c9ce9dc40aa8fb40b6a1ee65dd6650b6043\", \"skia_unittests\": \"303c5a73f77243b8bcf9e9699e4a2be34f4792c2\", \"snapshot_unittests\": \"7b1b3624f7287b3d6742d5cd6a81ac0d4fb41f90\", \"sql_unittests\": \"324ab32efdd7a7e5a0d1a8c78ea5e2acb6d953b9\", \"storage_unittests\": \"1a95060b40a303660113fc9c53fcba2af0652d26\", \"sync_integration_tests\": \"f79ea5c7bd24a4e3a245749064989d6edf16ae14\", \"traffic_annotation_auditor_unittests\": \"ffeb8528d6e97007399bc89b2d1de0e623b60233\", \"ui_base_unittests\": \"1ecc78af1d2f407bde1f6d7bf19fb937c3034e38\", \"ui_chromeos_unittests\": \"373d80e18fc4b83f6c642dda269eb939d1ece76b\", \"ui_touch_selection_unittests\": \"86518f21ea6c85dd4a2fb10a19da315ce92caeec\", \"unit_tests\": \"ff334601d248be4084e47f1b19407f9dd9487083\", \"url_unittests\": \"16290403628a89894149515ff8b0c2067bb81cdd\", \"usage_time_limit_unittests\": \"e1bbeea932b58839ef9eb7c7a60088bd42c92678\", \"views_unittests\": \"0d34aef85ebf8380333ed460c68452e5f39acd7e\", \"viz_unittests\": \"f1d0d3e18347f33a671edec84da2f4e1e72f7064\", \"wayland_client_perftests\": \"3238998ad30f8d15fda222908ad71ca3cb88930e\", \"wm_unittests\": \"c77df8febedcc460c9448da2d4161301fc830eab\", \"wtf_unittests\": \"fe9b15c8a29f90b867f5ca4038a25f00633e61e5\", \"zlib_unittests\": \"544d6db82a6d267d44744c6e8780ea8cf3f760d6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_8c23a72064650b4b1d9380637a287eed4ae2e2a2092116afea022d28ee09b5dd_v2\", \"builder_8e35dc9d0a811a7a5937b2845f7d6ff611dfc73c6ea10d73e1df62dc95746f2a_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_e9d7d0a19fc068adf341a224b6eb4c464e3f53ed9d47979ef0c80d8aa2ba711c_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-47-zair\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- ExternalProviderImplChromeOSTest.PriorityCompleted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611179549420234",
"status": "COMPLETED",
"status_changed_ts": "1611179550088939",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44775",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f3c7d413de9308c936a7a394d74f2122764e779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f3c7d413de9308c936a7a394d74f2122764e779",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022979574037070032",
"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/8857549511793035728/+/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:513a9a63cc251b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611183009886664",
"url": "https://ci.chromium.org/b/8857549511793035728",
"utcnow_ts": "1611183010479714"
}
}
[22:50:10.493] Invocation finished in 57m45.502880128s with status FAILED