[16:15:28.625] New invocation is queued and will start shortly
[16:15:29.697] Starting the invocation (attempt 1)
[16:15:29.732] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:15:29.732] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:15:29.732] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017746148395344048",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017746148395344048",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"gitiles_ref:refs/heads/master"
]
}
[16:15:30.938] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616170529952991",
"id": "8852316086343682880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616170530679894",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46277",
"builder:linux-chromeos-rel",
"buildset:commit/git/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017746148395344048",
"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/8852316086343682880/+/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": "1616170530680234",
"url": "https://ci.chromium.org/b/8852316086343682880",
"utcnow_ts": "1616170530926550"
}
}
[16:15:30.938] Task URL: https://ci.chromium.org/b/8852316086343682880
[16:15:30.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:2:0) after 1m0s
[16:16:31.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:2:0)
[16:16:31.000] Timer tick, asking Buildbucket for the build status
[16:16:31.107] Build 8852316086343682880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:31.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:3:0) after 1m0s
[16:16:55.057] Received PubSub notification, asking Buildbucket for the build status
[16:16:55.141] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:31.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:3:0)
[16:17:31.089] Timer tick, asking Buildbucket for the build status
[16:17:31.197] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:31.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:5:0) after 1m0s
[16:18:31.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:5:0)
[16:18:31.237] Timer tick, asking Buildbucket for the build status
[16:18:31.318] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:31.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:6:0) after 1m0s
[16:19:31.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:6:0)
[16:19:31.349] Timer tick, asking Buildbucket for the build status
[16:19:31.529] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:31.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:7:0) after 1m0s
[16:20:31.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:7:0)
[16:20:31.543] Timer tick, asking Buildbucket for the build status
[16:20:32.780] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:32.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:8:0) after 1m0s
[16:21:32.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:8:0)
[16:21:32.822] Timer tick, asking Buildbucket for the build status
[16:21:32.916] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:32.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:9:0) after 1m0s
[16:22:32.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:9:0)
[16:22:32.979] Timer tick, asking Buildbucket for the build status
[16:22:33.051] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:33.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:10:0) after 1m0s
[16:23:33.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:10:0)
[16:23:33.042] Timer tick, asking Buildbucket for the build status
[16:23:33.256] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:33.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:11:0) after 1m0s
[16:24:33.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:11:0)
[16:24:33.273] Timer tick, asking Buildbucket for the build status
[16:24:33.363] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:33.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:12:0) after 1m0s
[16:25:33.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:12:0)
[16:25:33.426] Timer tick, asking Buildbucket for the build status
[16:25:33.490] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:33.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:13:0) after 1m0s
[16:26:33.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:13:0)
[16:26:33.593] Timer tick, asking Buildbucket for the build status
[16:26:34.146] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:34.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:14:0) after 1m0s
[16:27:34.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:14:0)
[16:27:34.174] Timer tick, asking Buildbucket for the build status
[16:27:34.260] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:34.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:15:0) after 1m0s
[16:28:34.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:15:0)
[16:28:34.311] Timer tick, asking Buildbucket for the build status
[16:28:34.378] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:34.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:16:0) after 1m0s
[16:29:34.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:16:0)
[16:29:34.426] Timer tick, asking Buildbucket for the build status
[16:29:34.497] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:34.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:17:0) after 1m0s
[16:30:34.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:17:0)
[16:30:34.512] Timer tick, asking Buildbucket for the build status
[16:30:34.808] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:34.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:18:0) after 1m0s
[16:31:34.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:18:0)
[16:31:34.918] Timer tick, asking Buildbucket for the build status
[16:31:35.017] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:35.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:19:0) after 1m0s
[16:32:35.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:19:0)
[16:32:35.003] Timer tick, asking Buildbucket for the build status
[16:32:35.062] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:35.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:20:0) after 1m0s
[16:33:35.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:20:0)
[16:33:35.155] Timer tick, asking Buildbucket for the build status
[16:33:35.228] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:35.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:21:0) after 1m0s
[16:34:35.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:21:0)
[16:34:35.294] Timer tick, asking Buildbucket for the build status
[16:34:35.363] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:35.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:22:0) after 1m0s
[16:35:35.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:22:0)
[16:35:35.382] Timer tick, asking Buildbucket for the build status
[16:35:35.470] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:35.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:23:0) after 1m0s
[16:36:35.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:23:0)
[16:36:35.478] Timer tick, asking Buildbucket for the build status
[16:36:35.576] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:35.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:24:0) after 1m0s
[16:37:35.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:24:0)
[16:37:35.618] Timer tick, asking Buildbucket for the build status
[16:37:35.678] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:35.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:25:0) after 1m0s
[16:38:35.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:25:0)
[16:38:35.681] Timer tick, asking Buildbucket for the build status
[16:38:35.745] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:35.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:26:0) after 1m0s
[16:39:35.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:26:0)
[16:39:35.792] Timer tick, asking Buildbucket for the build status
[16:39:35.865] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:35.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:27:0) after 1m0s
[16:40:35.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:27:0)
[16:40:35.919] Timer tick, asking Buildbucket for the build status
[16:40:36.017] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:36.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:28:0) after 1m0s
[16:41:36.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:28:0)
[16:41:36.140] Timer tick, asking Buildbucket for the build status
[16:41:36.304] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:36.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:29:0) after 1m0s
[16:42:36.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:29:0)
[16:42:36.370] Timer tick, asking Buildbucket for the build status
[16:42:36.676] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:36.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:30:0) after 1m0s
[16:43:36.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:30:0)
[16:43:36.673] Timer tick, asking Buildbucket for the build status
[16:43:36.985] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:36.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:31:0) after 1m0s
[16:44:37.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:31:0)
[16:44:37.107] Timer tick, asking Buildbucket for the build status
[16:44:37.353] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:37.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:32:0) after 1m0s
[16:45:37.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:32:0)
[16:45:37.351] Timer tick, asking Buildbucket for the build status
[16:45:37.439] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:37.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:33:0) after 1m0s
[16:46:37.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:33:0)
[16:46:37.497] Timer tick, asking Buildbucket for the build status
[16:46:37.564] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:37.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:34:0) after 1m0s
[16:47:37.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:34:0)
[16:47:37.569] Timer tick, asking Buildbucket for the build status
[16:47:37.644] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:37.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:35:0) after 1m0s
[16:48:37.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:35:0)
[16:48:37.641] Timer tick, asking Buildbucket for the build status
[16:48:37.728] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:37.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:36:0) after 1m0s
[16:49:37.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:36:0)
[16:49:37.748] Timer tick, asking Buildbucket for the build status
[16:49:37.815] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:37.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:37:0) after 1m0s
[16:50:37.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:37:0)
[16:50:37.851] Timer tick, asking Buildbucket for the build status
[16:50:37.957] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:37.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:38:0) after 1m0s
[16:51:37.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:38:0)
[16:51:37.953] Timer tick, asking Buildbucket for the build status
[16:51:38.022] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:38.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:39:0) after 1m0s
[16:52:38.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:39:0)
[16:52:38.049] Timer tick, asking Buildbucket for the build status
[16:52:38.124] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:38.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:40:0) after 1m0s
[16:53:38.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:40:0)
[16:53:38.141] Timer tick, asking Buildbucket for the build status
[16:53:38.214] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:38.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:41:0) after 1m0s
[16:54:38.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:41:0)
[16:54:38.237] Timer tick, asking Buildbucket for the build status
[16:54:38.315] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:38.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:42:0) after 1m0s
[16:55:38.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:42:0)
[16:55:38.363] Timer tick, asking Buildbucket for the build status
[16:55:38.577] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:38.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:43:0) after 1m0s
[16:56:38.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:43:0)
[16:56:38.620] Timer tick, asking Buildbucket for the build status
[16:56:38.699] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:38.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:44:0) after 1m0s
[16:57:38.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:44:0)
[16:57:38.729] Timer tick, asking Buildbucket for the build status
[16:57:38.816] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:38.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:45:0) after 1m0s
[16:58:38.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:45:0)
[16:58:38.833] Timer tick, asking Buildbucket for the build status
[16:58:38.917] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:38.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:46:0) after 1m0s
[16:59:38.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:46:0)
[16:59:38.935] Timer tick, asking Buildbucket for the build status
[16:59:39.042] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:39.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:47:0) after 1m0s
[17:00:39.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:47:0)
[17:00:39.110] Timer tick, asking Buildbucket for the build status
[17:00:39.386] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:39.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:48:0) after 1m0s
[17:01:39.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:48:0)
[17:01:39.437] Timer tick, asking Buildbucket for the build status
[17:01:39.898] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:49:0) after 1m0s
[17:02:40.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:49:0)
[17:02:40.042] Timer tick, asking Buildbucket for the build status
[17:02:40.469] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:40.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:50:0) after 1m0s
[17:03:40.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:50:0)
[17:03:40.477] Timer tick, asking Buildbucket for the build status
[17:03:40.566] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:51:0) after 1m0s
[17:04:40.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:51:0)
[17:04:40.610] Timer tick, asking Buildbucket for the build status
[17:04:40.722] Build 8852316086343682880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:40.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017746148395344048:52:0) after 1m0s
[17:05:05.897] Received PubSub notification, asking Buildbucket for the build status
[17:05:06.033] Build 8852316086343682880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:05:06.033] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616173505585513",
"created_by": "project:chromium",
"created_ts": "1616170529952991",
"failure_reason": "BUILD_FAILURE",
"id": "8852316086343682880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AdsPageLoadMetricsObserverBrowserTest.OriginStatusMetricCross\"}, \"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\": \"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-83-a7j4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46277, \"got_angle_revision\": \"15a1e7357d3816c724f6001b31d7e0778e9195e1\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\", \"got_revision_cp\": \"refs/heads/master@{#864667}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"cb80f6e731e7e198ea5e9631149342ec2e189a0b\", \"got_v8_revision_cp\": \"refs/heads/9.1.161@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"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\": \"05d7cbd8fac2acf34500e4c049be1275c55cbcdb\", \"swarm_hashes_refs/heads/master(at){#864667}_without_patch\": {\"absl_hardening_tests\": \"a614ea13e0028045b408da9ee61e7fb144544386\", \"accessibility_unittests\": \"35b12d538bc8675a4088201e1ae8fd50a59c9154\", \"angle_unittests\": \"4b9196eb9e89f560a20244b147a5ca88a6644fb3\", \"app_list_unittests\": \"930e111e2334c54b92c7f365ab749300015ba1c7\", \"app_shell_unittests\": \"11b6cfcf43278a003017cca530f6d1788da7758d\", \"ash_unittests\": \"c0725ba991e93e4c17a45177906a5f59c8343913\", \"aura_unittests\": \"6c7a695ef0e3674b730b13d627ad1e0064d54ef8\", \"base_unittests\": \"115a81945b6aaa1c2da879bdc32f6c4a7043337d\", \"base_util_unittests\": \"a096b503a128d8e04d9569b704986d1e62c1a470\", \"blink_common_unittests\": \"ecd7cb6ae472d65bd7db5f3baa65da463335deba\", \"blink_fuzzer_unittests\": \"32c0e0f4ec1c06f5787c1e0baff150b3bc32cfc6\", \"blink_heap_unittests\": \"90201f7526dec71688585839d27ebb354b6fb215\", \"blink_platform_unittests\": \"eccae422d053eab3f0ecc15f028d65b35d3fad60\", \"blink_unittests\": \"a43c2848aea14d55a024f4dd52fbe84f491f512b\", \"boringssl_crypto_tests\": \"9363d4367679469f5848081ebe88d8d49dfe5e09\", \"boringssl_ssl_tests\": \"d484d6f6b7dd3a92d80d183d7a2fd7b4f032bd26\", \"browser_tests\": \"c0551a5f4749a462e18e9a452c93d89377652cdf\", \"capture_unittests\": \"a90ba60bc44c7a4998a0df568ce30aba032726e3\", \"cast_unittests\": \"379e3b2eda5f1cf82ed34beada3a66b93cdc71a5\", \"cc_unittests\": \"c59246bf63498b6ec370d8d3d1e0db46b05e939c\", \"chrome_app_unittests\": \"9d7b179146c645f59226e8c4622205b6dfcd57ef\", \"chromedriver_unittests\": \"c50fdf4fa5dc00a415ac4bdfdb0899709f7f97c7\", \"chromeos_components_unittests\": \"54b71bfebb60f3244db7d758efc250b17b971203\", \"chromeos_unittests\": \"d4337426e88659195b11c48fcad8296690540450\", \"color_unittests\": \"6e9807c18cbd6806835c72b8e945d6bca4cdb7c9\", \"components_browsertests\": \"45a9e78e92813eb37893f427e053d836599daf3c\", \"components_unittests\": \"fb62164d4a4c6ffa9129dca3fe26976509c60535\", \"compositor_unittests\": \"13d986e27a96e7b914ae4f27088585509d3e4f37\", \"content_browsertests\": \"538321c19988c0306aa5da61ddcec00202b4cc29\", \"content_unittests\": \"7228550d256010c67db0223b904fcbcdc1f46672\", \"crashpad_tests\": \"2669b68eef4f4f2770b3fb98fbd6a354a0cd39ca\", \"crypto_unittests\": \"8cf170dce901ea2857e6e39c5af7415362b055d6\", \"dbus_unittests\": \"c15afc2e661d3e6d12866c8c4a471d09d7a1e377\", \"device_unittests\": \"d71460a7d6b41d16815b88967d5336ad1ac7c8df\", \"display_unittests\": \"b2bc290758ab43b86aaccc2c4b3b5c51b4709806\", \"events_unittests\": \"3ee90f1b3cfd0d8a656fea58c046e6309db352a0\", \"exo_unittests\": \"55a02789a60cc51a695e5574484d5b0f7a76cb5c\", \"extensions_browsertests\": \"f66f717ee4384cd9de8a2e28c8b2bb9cdbd0ea6f\", \"extensions_unittests\": \"ecf32e81ba6f1deef5508b1b0b5c2d412efc0318\", \"filesystem_service_unittests\": \"8610125c6961e041f4e6cbb9f1109effe7480c73\", \"gcm_unit_tests\": \"30a988cb45adfa9b971d8b5e885888e9b5cbd49c\", \"gfx_unittests\": \"e315512cb092cedf6cd62de406dad2617fdc5052\", \"gin_unittests\": \"260dd41ffcfb785c4f10dd181ceb162f91b53f60\", \"gl_unittests_ozone\": \"6beb29e030fad1960be0a19cad4f549ec66457b3\", \"google_apis_unittests\": \"bce3e954b486add2862b270fed41b1bdae0bd7e4\", \"gpu_unittests\": \"9fc73e55d308d78f33a91062ed49b3bcfd1bbdff\", \"gwp_asan_unittests\": \"8aced90ebb82a433233925a00bb82ccf0e82393a\", \"interactive_ui_tests\": \"c11c0f703d59b787cb0b4632f8e05ab05c9bf89c\", \"ipc_tests\": \"6307922877a85b67ceb9241f4561425642edaf62\", \"jingle_unittests\": \"a5bed1c235b745e8f87042997641b4f2d1838cfa\", \"keyboard_unittests\": \"c9602ce8422316faad29d0be916d19ec5ee5902d\", \"latency_unittests\": \"0de5da33357111354c1b6e2cab30d92459d9bff1\", \"libjingle_xmpp_unittests\": \"513c1c9f69a0998f2eae97f96cd9c22d6b46f288\", \"liburlpattern_unittests\": \"5bd3e7926ad5e890e436c923a20ec36fdeec5838\", \"media_blink_unittests\": \"c941ad35178a9f4e3eb5ed1fd799242b21937ddd\", \"media_unittests\": \"08603f0834356e18e081d0ff04c72e51cf348419\", \"message_center_unittests\": \"4da07ca43ba8a36e6829a954f2340de32275b330\", \"midi_unittests\": \"6a74d7c79d0bd9298075c08252edee7f5b2a34fe\", \"mojo_core_unittests\": \"f05a9d6ae07f4360bcd4dde807b97ec82f260b39\", \"mojo_unittests\": \"0d59e372228cc15e4cb3096ae926f6fbb49a24d0\", \"nacl_helper_nonsfi_unittests\": \"2303090f5708d7498095081408f60c6bd23f52b5\", \"nacl_loader_unittests\": \"b4f8e8dc9daea820e68c784c472db128eaae6aaa\", \"native_theme_unittests\": \"4e9264f01cecc2bf68cec01cd69d041b394e619f\", \"net_unittests\": \"8470a0dc8ac9e0e2642db0ec2be8d1df2d73ccc6\", \"ozone_gl_unittests\": \"d972347be8aa95bdf42f6b82da4cc82a3ffa3b5c\", \"ozone_unittests\": \"f53a31576c5b38ad7737730a768800bbad0a7bd0\", \"ozone_x11_unittests\": \"d31ed799b5b95dfa951fbef1d1e42b6744c0de63\", \"pdf_unittests\": \"efbaf6e3e99a61c0534965c0cd16d56f3ac26558\", \"perfetto_unittests\": \"9ac370c82d12c5a24fa0b858cd798c9fec04edf3\", \"ppapi_unittests\": \"ef22f629eb6fce0c522d10a7d74b3b1ba809be5f\", \"printing_unittests\": \"6c00f09bb027e3606cce08c3f6b0f76a832d551e\", \"remoting_unittests\": \"dfced82f26661513e3f1089ada9f75067c8a293e\", \"sandbox_linux_unittests\": \"7b7241c40820941b25602b35297a6fc73f5c673a\", \"service_manager_unittests\": \"2a9f7546aca844667069a6e0bf859f3b42cf51ef\", \"services_unittests\": \"cf32e59377022d441b68284778c07604bb72b99b\", \"shell_dialogs_unittests\": \"d007ef4b9a79a8461f8ef154738178ec1ae7bada\", \"shell_encryption_unittests\": \"809372826de1b41bc9de13035f40d4782652fe69\", \"skia_unittests\": \"ef2d6e69c2714e476e0e58759392db44f7b40547\", \"snapshot_unittests\": \"d0bdda2b677bae43db2d84ed8a44ba3f5dd00468\", \"sql_unittests\": \"ea218693d060708ba235663de38f91625b53e278\", \"storage_unittests\": \"3b564b8b10f465113499c8823eda74b3367e8262\", \"sync_integration_tests\": \"f46fc344e2fea8c0ec5e671f7adf87eb98b5d074\", \"traffic_annotation_auditor_unittests\": \"03994f096babd6f52f1d0ef0a61ca90b5061d3fc\", \"ui_base_unittests\": \"92fd7e21226e5c36a687e4d594aed8f57cedf66e\", \"ui_chromeos_unittests\": \"56e435f382f312ef1353f27ae89c87b72f930cb9\", \"ui_touch_selection_unittests\": \"c3fba32dd3d39564456c251394e39b21e7d2de63\", \"unit_tests\": \"690bf213be8279711a5c3089a8f601ff1fbd3234\", \"url_unittests\": \"6e1500b2047ca58c7adad752122fbd3db6298269\", \"usage_time_limit_unittests\": \"30061225d5ff7f96e67dcf329b0cd424c453dfb6\", \"views_unittests\": \"a423c50dc17ac593670a450705f97e6790025c23\", \"viz_unittests\": \"b659b19598810f6efb6373396269a78f74b14592\", \"wayland_client_perftests\": \"592f35f9eceadd139e219839283e18121fa5369a\", \"wayland_client_tests\": \"14c121f4650c0fe6798a116e3f6af8008f55e08e\", \"wm_unittests\": \"531e10c1a19dbf2d421db62f018e240f985551c9\", \"wtf_unittests\": \"2418d9032687f6f9ce31991321636648401e73dc\", \"zlib_unittests\": \"08cb0979e5817cecf1302a088ef27548d20fcc9e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_69f2a046c912b41289f97ea97f722be7d6fdad062770d964bdaa123063be4a56_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-83-a7j4\"], \"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\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AdsPageLoadMetricsObserverBrowserTest.OriginStatusMetricCross\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616170611869835",
"status": "COMPLETED",
"status_changed_ts": "1616170614733193",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46277",
"builder:linux-chromeos-rel",
"buildset:commit/git/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d7cbd8fac2acf34500e4c049be1275c55cbcdb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017746148395344048",
"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/8852316086343682880/+/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:526416c138517310",
"user_agent:luci-scheduler"
],
"updated_ts": "1616173505586115",
"url": "https://ci.chromium.org/b/8852316086343682880",
"utcnow_ts": "1616173506012767"
}
}
[17:05:06.033] Invocation finished in 49m37.421742001s with status FAILED