[17:18:46.271] New invocation is queued and will start shortly
[17:18:47.557] Starting the invocation (attempt 1)
[17:18:47.596] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:18:47.596] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:18:47.596] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019101120775031456",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019101120775031456",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"gitiles_ref:refs/heads/master"
]
}
[17:18:48.478] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614878327737996",
"id": "8853671058573244608",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614878328233388",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45913",
"builder:linux-chromeos-rel",
"buildset:commit/git/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019101120775031456",
"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/8853671058573244608/+/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": "1614878328233514",
"url": "https://ci.chromium.org/b/8853671058573244608",
"utcnow_ts": "1614878328469931"
}
}
[17:18:48.478] Task URL: https://ci.chromium.org/b/8853671058573244608
[17:18:48.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:2:0) after 1m0s
[17:19:22.807] Received PubSub notification, asking Buildbucket for the build status
[17:19:24.042] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:2:0)
[17:19:48.497] Timer tick, asking Buildbucket for the build status
[17:19:48.606] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:4:0) after 1m0s
[17:20:48.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:4:0)
[17:20:48.622] Timer tick, asking Buildbucket for the build status
[17:20:49.060] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:49.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:5:0) after 1m0s
[17:21:49.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:5:0)
[17:21:49.088] Timer tick, asking Buildbucket for the build status
[17:21:49.363] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:49.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:6:0) after 1m0s
[17:22:49.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:6:0)
[17:22:49.347] Timer tick, asking Buildbucket for the build status
[17:22:49.420] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:49.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:7:0) after 1m0s
[17:23:49.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:7:0)
[17:23:49.468] Timer tick, asking Buildbucket for the build status
[17:23:49.579] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:49.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:8:0) after 1m0s
[17:24:49.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:8:0)
[17:24:49.561] Timer tick, asking Buildbucket for the build status
[17:24:49.642] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:49.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:9:0) after 1m0s
[17:25:49.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:9:0)
[17:25:49.771] Timer tick, asking Buildbucket for the build status
[17:25:49.865] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:49.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:10:0) after 1m0s
[17:26:49.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:10:0)
[17:26:49.888] Timer tick, asking Buildbucket for the build status
[17:26:49.965] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:49.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:11:0) after 1m0s
[17:27:49.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:11:0)
[17:27:49.975] Timer tick, asking Buildbucket for the build status
[17:27:50.054] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:50.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:12:0) after 1m0s
[17:28:50.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:12:0)
[17:28:50.071] Timer tick, asking Buildbucket for the build status
[17:28:50.659] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:50.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:13:0) after 1m0s
[17:29:50.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:13:0)
[17:29:50.681] Timer tick, asking Buildbucket for the build status
[17:29:50.764] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:50.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:14:0) after 1m0s
[17:30:50.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:14:0)
[17:30:50.812] Timer tick, asking Buildbucket for the build status
[17:30:51.146] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:51.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:15:0) after 1m0s
[17:31:51.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:15:0)
[17:31:51.151] Timer tick, asking Buildbucket for the build status
[17:31:51.345] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:51.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:16:0) after 1m0s
[17:32:51.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:16:0)
[17:32:51.338] Timer tick, asking Buildbucket for the build status
[17:32:52.838] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:52.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:17:0) after 1m0s
[17:33:52.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:17:0)
[17:33:52.865] Timer tick, asking Buildbucket for the build status
[17:33:53.122] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:53.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:18:0) after 1m0s
[17:34:53.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:18:0)
[17:34:53.142] Timer tick, asking Buildbucket for the build status
[17:34:53.557] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:53.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:19:0) after 1m0s
[17:35:53.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:19:0)
[17:35:53.563] Timer tick, asking Buildbucket for the build status
[17:35:53.871] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:53.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:20:0) after 1m0s
[17:36:53.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:20:0)
[17:36:53.923] Timer tick, asking Buildbucket for the build status
[17:36:53.999] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:53.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:21:0) after 1m0s
[17:37:54.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:21:0)
[17:37:54.001] Timer tick, asking Buildbucket for the build status
[17:37:54.083] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:54.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:22:0) after 1m0s
[17:38:54.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:22:0)
[17:38:54.077] Timer tick, asking Buildbucket for the build status
[17:38:54.185] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:54.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:23:0) after 1m0s
[17:39:54.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:23:0)
[17:39:54.200] Timer tick, asking Buildbucket for the build status
[17:39:54.272] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:54.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:24:0) after 1m0s
[17:40:54.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:24:0)
[17:40:54.291] Timer tick, asking Buildbucket for the build status
[17:40:54.901] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:54.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:25:0) after 1m0s
[17:41:54.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:25:0)
[17:41:54.937] Timer tick, asking Buildbucket for the build status
[17:41:55.042] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:55.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:26:0) after 1m0s
[17:42:55.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:26:0)
[17:42:55.085] Timer tick, asking Buildbucket for the build status
[17:42:55.171] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:55.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:27:0) after 1m0s
[17:43:55.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:27:0)
[17:43:55.201] Timer tick, asking Buildbucket for the build status
[17:43:55.650] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:55.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:28:0) after 1m0s
[17:44:55.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:28:0)
[17:44:55.677] Timer tick, asking Buildbucket for the build status
[17:44:55.760] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:55.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:29:0) after 1m0s
[17:45:55.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:29:0)
[17:45:55.795] Timer tick, asking Buildbucket for the build status
[17:45:56.193] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:56.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:30:0) after 1m0s
[17:46:56.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:30:0)
[17:46:56.300] Timer tick, asking Buildbucket for the build status
[17:46:56.434] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:56.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:31:0) after 1m0s
[17:47:56.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:31:0)
[17:47:56.473] Timer tick, asking Buildbucket for the build status
[17:47:56.566] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:56.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:32:0) after 1m0s
[17:48:56.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:32:0)
[17:48:56.617] Timer tick, asking Buildbucket for the build status
[17:48:56.704] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:56.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:33:0) after 1m0s
[17:49:56.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:33:0)
[17:49:56.731] Timer tick, asking Buildbucket for the build status
[17:49:56.831] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:56.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:34:0) after 1m0s
[17:50:56.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:34:0)
[17:50:56.828] Timer tick, asking Buildbucket for the build status
[17:50:56.924] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:56.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:35:0) after 1m0s
[17:51:57.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:35:0)
[17:51:57.117] Timer tick, asking Buildbucket for the build status
[17:51:57.255] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:57.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:36:0) after 1m0s
[17:52:57.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:36:0)
[17:52:57.589] Timer tick, asking Buildbucket for the build status
[17:52:57.681] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:57.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:37:0) after 1m0s
[17:53:58.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:37:0)
[17:53:58.127] Timer tick, asking Buildbucket for the build status
[17:53:58.290] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:58.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:38:0) after 1m0s
[17:54:58.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:38:0)
[17:54:58.281] Timer tick, asking Buildbucket for the build status
[17:54:58.341] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:58.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:39:0) after 1m0s
[17:55:58.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:39:0)
[17:55:58.380] Timer tick, asking Buildbucket for the build status
[17:55:58.469] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:58.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:40:0) after 1m0s
[17:56:58.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:40:0)
[17:56:58.547] Timer tick, asking Buildbucket for the build status
[17:56:58.611] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:58.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:41:0) after 1m0s
[17:57:58.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:41:0)
[17:57:58.675] Timer tick, asking Buildbucket for the build status
[17:57:58.751] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:58.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:42:0) after 1m0s
[17:58:58.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:42:0)
[17:58:58.787] Timer tick, asking Buildbucket for the build status
[17:58:59.027] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:59.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:43:0) after 1m0s
[17:59:59.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:43:0)
[17:59:59.066] Timer tick, asking Buildbucket for the build status
[17:59:59.142] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:59.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:44:0) after 1m0s
[18:00:59.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:44:0)
[18:00:59.186] Timer tick, asking Buildbucket for the build status
[18:00:59.421] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:59.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:45:0) after 1m0s
[18:01:59.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:45:0)
[18:01:59.638] Timer tick, asking Buildbucket for the build status
[18:01:59.734] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:59.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:46:0) after 1m0s
[18:02:59.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:46:0)
[18:02:59.780] Timer tick, asking Buildbucket for the build status
[18:02:59.869] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:59.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:47:0) after 1m0s
[18:04:00.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:47:0)
[18:04:00.263] Timer tick, asking Buildbucket for the build status
[18:04:01.356] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:01.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:48:0) after 1m0s
[18:05:01.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:48:0)
[18:05:01.350] Timer tick, asking Buildbucket for the build status
[18:05:01.604] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:01.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:49:0) after 1m0s
[18:06:01.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:49:0)
[18:06:01.622] Timer tick, asking Buildbucket for the build status
[18:06:02.271] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:02.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:50:0) after 1m0s
[18:07:02.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:50:0)
[18:07:02.291] Timer tick, asking Buildbucket for the build status
[18:07:02.383] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:02.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:51:0) after 1m0s
[18:08:02.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:51:0)
[18:08:02.358] Timer tick, asking Buildbucket for the build status
[18:08:02.440] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:02.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:52:0) after 1m0s
[18:09:02.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:52:0)
[18:09:02.463] Timer tick, asking Buildbucket for the build status
[18:09:02.554] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:02.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:53:0) after 1m0s
[18:10:02.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:53:0)
[18:10:02.576] Timer tick, asking Buildbucket for the build status
[18:10:02.675] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:02.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:54:0) after 1m0s
[18:11:02.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:54:0)
[18:11:02.845] Timer tick, asking Buildbucket for the build status
[18:11:02.945] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:02.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:55:0) after 1m0s
[18:12:03.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:55:0)
[18:12:03.120] Timer tick, asking Buildbucket for the build status
[18:12:03.227] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:03.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:56:0) after 1m0s
[18:13:03.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:56:0)
[18:13:03.303] Timer tick, asking Buildbucket for the build status
[18:13:03.513] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:03.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:57:0) after 1m0s
[18:14:03.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:57:0)
[18:14:03.552] Timer tick, asking Buildbucket for the build status
[18:14:03.733] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:03.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:58:0) after 1m0s
[18:15:03.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:58:0)
[18:15:03.814] Timer tick, asking Buildbucket for the build status
[18:15:03.926] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:03.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:59:0) after 1m0s
[18:16:04.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:59:0)
[18:16:04.017] Timer tick, asking Buildbucket for the build status
[18:16:04.181] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:04.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:60:0) after 1m0s
[18:17:04.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:60:0)
[18:17:04.237] Timer tick, asking Buildbucket for the build status
[18:17:04.316] Build 8853671058573244608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:04.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019101120775031456:61:0) after 1m0s
[18:17:52.478] Received PubSub notification, asking Buildbucket for the build status
[18:17:52.564] Build 8853671058573244608: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:17:52.564] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614881871646910",
"created_by": "project:chromium",
"created_ts": "1614878327737996",
"failure_reason": "BUILD_FAILURE",
"id": "8853671058573244608",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/SitePerProcessBrowserTest.TouchEventAckQueueOrdering/0\"}, \"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\": \"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\", \"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-203-yawm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45913, \"got_angle_revision\": \"90db604919ba6e3a5ffbdd43845ac33f91e7ddfa\", \"got_dawn_revision\": \"d9a96fb28648eaff2c54beca62f14c718f042217\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\", \"got_revision_cp\": \"refs/heads/master@{#859812}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"084529f3a9b9992a0de73fe333f677bced7b8fdb\", \"got_v8_revision\": \"5134dc63648b2cf1d5f95604dc1d87520f121443\", \"got_v8_revision_cp\": \"refs/heads/9.1.44@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"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\": \"174c8fdaa25c49ca36b702598f3fc08f72cc4f29\", \"swarm_hashes_refs/heads/master(at){#859812}_without_patch\": {\"absl_hardening_tests\": \"74156c66f8c757fb089d38c358d40430370b6813\", \"accessibility_unittests\": \"ca669c146714b0c24585fc6e470f708fb2a46f30\", \"angle_unittests\": \"171b4c4f111752ae37aa354300f428d682f96346\", \"app_list_unittests\": \"25439de49a96e13c7244be7fed9ba0222f5fd3da\", \"app_shell_unittests\": \"9ddd08fabccf06353f9e4d432b78bdbbfb9e48d2\", \"ash_unittests\": \"926d629abb0acd80150315c19a8311398b86835e\", \"aura_unittests\": \"939136a28120283ca80d82c42a88d95331edc188\", \"base_unittests\": \"b29c696f49b9c1eb759967741f15934f45a592b7\", \"base_util_unittests\": \"72228ae7e99e7cbdd9b1a156bd6bd2f59343004f\", \"blink_common_unittests\": \"19fbdddf2f5c6bf5402b26f55abf4b1bee081d74\", \"blink_fuzzer_unittests\": \"02f69fd3e94d6da2ee85c30320f4377a6df0f652\", \"blink_heap_unittests\": \"22b0a99712204f1b8c1e3d1f95ee59c18edf6063\", \"blink_platform_unittests\": \"358a596adbe2d09167c30db0e0b4b1a4fc153875\", \"blink_unittests\": \"6ec9a9ce4d0953d0ecd7f7b6efd6c0835a573f61\", \"boringssl_crypto_tests\": \"ccd71a8b2e0fd434c419aa7a3952433b680d17c0\", \"boringssl_ssl_tests\": \"60099910fde29901b34e0f235bceae1736615b8e\", \"browser_tests\": \"d0e6bafab109c56207b950c212284006cc428db4\", \"capture_unittests\": \"0e53abf5e4a0c3bad2e510924224d1bd4b2ae74f\", \"cast_unittests\": \"52d69bc94e89483aa0aa2fde0f69c22c4f13319c\", \"cc_unittests\": \"b564a7896030d5808b8e93c19515671ef3dca776\", \"chrome_app_unittests\": \"947ad6109bf44bfd956bedf69b02fdbed85646b9\", \"chromedriver_unittests\": \"72eb25ded8d11e67e49a2e1206e4325e265e1fdb\", \"chromeos_components_unittests\": \"145097cca8387cd02c8f44fa450c2db092bcdd2c\", \"chromeos_unittests\": \"c74fba480ba3337164c5ee7f98dffd18316d2acd\", \"color_unittests\": \"20963644f07afcac7e9ccd51e477687f9b10c36f\", \"components_browsertests\": \"deffaa6f080bdaa6a79aaf30b193b6a7400d750c\", \"components_unittests\": \"db7d39ed145a6d1f0c5099f327104f322550c512\", \"compositor_unittests\": \"4c028b391e5a3056626a1f27103e28bdf06cd77a\", \"content_browsertests\": \"becda24a0d682c3f9ee6e86e9ca8f136d6f0ab0f\", \"content_unittests\": \"ee877bb7b36fc147abc53bdcfc25c3f2e2a69c67\", \"crashpad_tests\": \"138d8e483698979282b5c6e6aaa763a8a242a2aa\", \"crypto_unittests\": \"3b906656f8fb75bc71875c21fe56373e2e6aa75b\", \"dbus_unittests\": \"df70795b6b3d17d80787f8a966679170896c53a6\", \"device_unittests\": \"6cd1d71ba82338fc8ea02dc6f0d83827f45c5b39\", \"display_unittests\": \"33ef9c081cad4a979398ff1189e58db7193921bf\", \"events_unittests\": \"bafd49ad1a065e07205ef8c4ccf863ef9869f1e1\", \"exo_unittests\": \"2c753cf74f5b4c99550b3c1e1726fc73404b344e\", \"extensions_browsertests\": \"a856a5fa0e32c56cc557adce1efcfb6e9c3e41fc\", \"extensions_unittests\": \"48960008ba822029328a446329586cebe74b5d31\", \"filesystem_service_unittests\": \"aaeff51ee18396ebdd130b0ce37a80add2da51d6\", \"gcm_unit_tests\": \"2adbb30560f672d7041f581796147d64b5b40be7\", \"gfx_unittests\": \"2cb6a6ec09e1def0c41a37821c65ed3d0c53598a\", \"gin_unittests\": \"8adee090df3cfca65b05547833d6ca6df48ff9cc\", \"gl_unittests_ozone\": \"39c8b0878dee27a52e526f67308990684e7bb8bc\", \"google_apis_unittests\": \"664c4f9729bce0c3805e717fb455349946f23415\", \"gpu_unittests\": \"e131cf2eec0c70ed260375a6faae391519805269\", \"gwp_asan_unittests\": \"dc376fbca1641c28eff4a714bc2e0a95b94a8b5c\", \"interactive_ui_tests\": \"65897e3328049b10ec233f93686ec86bee43443b\", \"ipc_tests\": \"c7cbb6b2257b92a5a648d9820b0194e78d7c3322\", \"jingle_unittests\": \"139c66974eeaf18b31c9d3945b0d64094fa97591\", \"keyboard_unittests\": \"11e0b6b00dbb299981c10f161afb03562790c0eb\", \"latency_unittests\": \"a4f99837e7e12a1bd6cfbc3509fd77e8b2e0f74f\", \"libjingle_xmpp_unittests\": \"e8cf040b8c8342650ac9597e251141fbe889f4a0\", \"liburlpattern_unittests\": \"6457a3475e7c09193b5ffd502d6a9d65a0c3f5c0\", \"media_blink_unittests\": \"78a1ae1fe214c5baf4997ed9f026446a8d6655a8\", \"media_unittests\": \"df40f3fa1d32228af3f8702fe7d50488c52b5777\", \"message_center_unittests\": \"e28bebe65890fce34da270cc7b1f0f3138100525\", \"midi_unittests\": \"2c3f8e2be7a13c0b7646e6d57f8d36523194e4b4\", \"mojo_core_unittests\": \"0cf6addcb006351ea0994dfb7b8caf6874598f66\", \"mojo_unittests\": \"4d9c03313ce8c2f0b1e0195469d64a18f54407d9\", \"nacl_helper_nonsfi_unittests\": \"79d1b89d3096176ed33fb5393cb0d3371da9d1d3\", \"nacl_loader_unittests\": \"b2680a7f270247fcc4070c07ce1f187eaa0fa18c\", \"native_theme_unittests\": \"0cc2f560c62970bd7bd0cfe62935cb8df8b3227c\", \"net_unittests\": \"80b9b5e11fec5f6c009838bdb49038bd16c27cb5\", \"ozone_gl_unittests\": \"f603ca847bce6431ff54f10f4306e4a201387df2\", \"ozone_unittests\": \"aede0e2235f317a6c5075ba3f2ee523404c05960\", \"ozone_x11_unittests\": \"bec5c91007be652535627cac7a8e1e3cae1a0bfb\", \"pdf_unittests\": \"996bc7d54a7184559f9fd15766f56052dea53538\", \"perfetto_unittests\": \"d5b653e01329b24136b9e0122721ae1388ba9cf5\", \"ppapi_unittests\": \"e488a3eb6ddb383376b918e855f47ada4fa1c5fc\", \"printing_unittests\": \"56eebf54331a3243551f5c89f2eb24eec724fe27\", \"remoting_unittests\": \"60e0a0d4ebec07955761357340bfdd41c403d36f\", \"sandbox_linux_unittests\": \"2d8f8595f77f6f48199567a472fb4e8f4ff26c76\", \"service_manager_unittests\": \"04c2b09187756c618e9968446b4618bc21dc7591\", \"services_unittests\": \"ba7e6f6dee9853edc7a381d6b2db3467a6254bab\", \"shell_dialogs_unittests\": \"56977a8d9b0c04e0dd517729de5eeca265e524c5\", \"shell_encryption_unittests\": \"d5e61432c82895faa8f230c3c7d07735c0444ad3\", \"skia_unittests\": \"7d45dcc1b9176a4f6bad0f967330adba747695c9\", \"snapshot_unittests\": \"39e2180938423548a8a2da5a6123a2bd72d4923d\", \"sql_unittests\": \"268f7f889e84185a5b4d9e7a7064630bbb59d534\", \"storage_unittests\": \"c6ab25de6cb07d262d8ca2a04462dac5f4a3a792\", \"sync_integration_tests\": \"e1ab4f74233c7241221e4220d9e79b802d917e56\", \"traffic_annotation_auditor_unittests\": \"c1bd046cd9a1229ce3be6d1ed6aed34d5e1721c5\", \"ui_base_unittests\": \"cbc7864e319fed84426817357db97924bcff93ad\", \"ui_chromeos_unittests\": \"87833490543276f0ac1da77ee4884b776aaf61f7\", \"ui_touch_selection_unittests\": \"4da6011f62d9ebd88e7ddefad12f447cf1b61d1a\", \"unit_tests\": \"8c5b48624bb2afe936f4e3d98dec4321e13745d9\", \"url_unittests\": \"61aeffb0f3a397f4648445ccb8c4247f93066a45\", \"usage_time_limit_unittests\": \"552ee4a73ba38f8e9cb8efdbf94c65e26ac309a7\", \"views_unittests\": \"e42760235b131289f99c7f194fd9fb4f8899c0ee\", \"viz_unittests\": \"cc80245e8c826f0318a7b1bdcfdd9489ed193af3\", \"wayland_client_perftests\": \"39f764c75cba5cd5285743082bd78f1c54df59d2\", \"wayland_client_tests\": \"8573752a48c2f58a3096d7279d4f81b9bdd9703f\", \"wm_unittests\": \"8323e8a5396512ea7d7c9b9254e290a17a805022\", \"wtf_unittests\": \"bc5f49939cf7e72be1f5fae90a482c314a89c4a2\", \"zlib_unittests\": \"44d1e273942a1a1442bd05c826d668f410ce7648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_1b9daa149b4790c306d4115e8498bce8e4004f87fdf8195a8bb15f493be69c40_v2\", \"builder_4500df524e03abdc7ff29c0f916c0f69df935ae1f41d97a45c59dd04b0afce26_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9bfd7171a3bbd1a8f2af02f1311f913ecc6693b330c40ec1ccf845ae76a71675_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-203-yawm\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/SitePerProcessBrowserTest.TouchEventAckQueueOrdering/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614878361900158",
"status": "COMPLETED",
"status_changed_ts": "1614878362516411",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45913",
"builder:linux-chromeos-rel",
"buildset:commit/git/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/174c8fdaa25c49ca36b702598f3fc08f72cc4f29",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019101120775031456",
"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/8853671058573244608/+/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:521711504c0d1410",
"user_agent:luci-scheduler"
],
"updated_ts": "1614881871647230",
"url": "https://ci.chromium.org/b/8853671058573244608",
"utcnow_ts": "1614881872555327"
}
}
[18:17:52.564] Invocation finished in 59m6.309415326s with status FAILED