[02:30:42.135] New invocation is queued and will start shortly
[02:30:43.245] Starting the invocation (attempt 1)
[02:30:43.296] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:30:43.296] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:30:43.296] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022327887063868912",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a74a2c5df89b3141a2f16877d19f59c17ddcad49\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022327887063868912",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"gitiles_ref:refs/heads/master"
]
}
[02:30:44.005] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611801043405804",
"id": "8856897825068867600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a74a2c5df89b3141a2f16877d19f59c17ddcad49\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611801043836149",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44959",
"builder:linux-chromeos-rel",
"buildset:commit/git/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022327887063868912",
"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/8856897825068867600/+/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": "1611801043836258",
"url": "https://ci.chromium.org/b/8856897825068867600",
"utcnow_ts": "1611801043993405"
}
}
[02:30:44.005] Task URL: https://ci.chromium.org/b/8856897825068867600
[02:30:44.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:2:0) after 1m0s
[02:31:44.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:2:0)
[02:31:44.064] Timer tick, asking Buildbucket for the build status
[02:31:44.303] Build 8856897825068867600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:44.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:3:0) after 1m0s
[02:32:17.719] Received PubSub notification, asking Buildbucket for the build status
[02:32:17.828] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:3:0)
[02:32:44.324] Timer tick, asking Buildbucket for the build status
[02:32:44.615] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:5:0) after 1m0s
[02:33:44.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:5:0)
[02:33:44.646] Timer tick, asking Buildbucket for the build status
[02:33:44.773] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:44.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:6:0) after 1m0s
[02:34:44.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:6:0)
[02:34:44.827] Timer tick, asking Buildbucket for the build status
[02:34:44.900] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:44.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:7:0) after 1m0s
[02:35:45.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:7:0)
[02:35:45.003] Timer tick, asking Buildbucket for the build status
[02:35:45.085] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:45.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:8:0) after 1m0s
[02:36:45.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:8:0)
[02:36:45.164] Timer tick, asking Buildbucket for the build status
[02:36:45.767] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:45.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:9:0) after 1m0s
[02:37:45.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:9:0)
[02:37:45.810] Timer tick, asking Buildbucket for the build status
[02:37:47.474] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:47.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:10:0) after 1m0s
[02:38:47.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:10:0)
[02:38:47.708] Timer tick, asking Buildbucket for the build status
[02:38:47.845] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:47.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:11:0) after 1m0s
[02:39:48.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:11:0)
[02:39:48.031] Timer tick, asking Buildbucket for the build status
[02:39:48.285] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:48.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:12:0) after 1m0s
[02:40:48.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:12:0)
[02:40:48.308] Timer tick, asking Buildbucket for the build status
[02:40:48.473] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:48.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:13:0) after 1m0s
[02:41:48.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:13:0)
[02:41:48.526] Timer tick, asking Buildbucket for the build status
[02:41:48.931] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:48.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:14:0) after 1m0s
[02:42:49.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:14:0)
[02:42:49.048] Timer tick, asking Buildbucket for the build status
[02:42:49.122] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:49.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:15:0) after 1m0s
[02:43:49.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:15:0)
[02:43:49.179] Timer tick, asking Buildbucket for the build status
[02:43:49.293] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:49.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:16:0) after 1m0s
[02:44:49.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:16:0)
[02:44:49.339] Timer tick, asking Buildbucket for the build status
[02:44:49.507] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:49.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:17:0) after 1m0s
[02:45:49.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:17:0)
[02:45:49.611] Timer tick, asking Buildbucket for the build status
[02:45:49.724] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:18:0) after 1m0s
[02:46:49.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:18:0)
[02:46:49.920] Timer tick, asking Buildbucket for the build status
[02:46:50.045] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:50.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:19:0) after 1m0s
[02:47:50.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:19:0)
[02:47:50.152] Timer tick, asking Buildbucket for the build status
[02:47:50.244] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:50.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:20:0) after 1m0s
[02:48:50.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:20:0)
[02:48:50.263] Timer tick, asking Buildbucket for the build status
[02:48:50.355] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:50.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:21:0) after 1m0s
[02:49:50.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:21:0)
[02:49:50.467] Timer tick, asking Buildbucket for the build status
[02:49:50.801] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:50.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:22:0) after 1m0s
[02:50:50.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:22:0)
[02:50:50.901] Timer tick, asking Buildbucket for the build status
[02:50:51.083] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:51.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:23:0) after 1m0s
[02:51:51.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:23:0)
[02:51:51.264] Timer tick, asking Buildbucket for the build status
[02:51:51.371] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:51.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:24:0) after 1m0s
[02:52:51.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:24:0)
[02:52:51.418] Timer tick, asking Buildbucket for the build status
[02:52:51.530] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:51.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:25:0) after 1m0s
[02:53:51.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:25:0)
[02:53:51.552] Timer tick, asking Buildbucket for the build status
[02:53:51.635] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:51.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:26:0) after 1m0s
[02:54:51.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:26:0)
[02:54:51.766] Timer tick, asking Buildbucket for the build status
[02:54:51.851] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:27:0) after 1m0s
[02:55:51.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:27:0)
[02:55:51.948] Timer tick, asking Buildbucket for the build status
[02:55:52.482] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:52.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:28:0) after 1m0s
[02:56:52.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:28:0)
[02:56:52.548] Timer tick, asking Buildbucket for the build status
[02:56:52.669] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:52.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:29:0) after 1m0s
[02:57:52.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:29:0)
[02:57:52.741] Timer tick, asking Buildbucket for the build status
[02:57:52.946] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:52.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:30:0) after 1m0s
[02:58:53.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:30:0)
[02:58:53.077] Timer tick, asking Buildbucket for the build status
[02:58:53.272] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:31:0) after 1m0s
[02:59:53.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:31:0)
[02:59:53.376] Timer tick, asking Buildbucket for the build status
[02:59:53.580] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:53.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:32:0) after 1m0s
[03:00:53.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:32:0)
[03:00:53.628] Timer tick, asking Buildbucket for the build status
[03:00:54.920] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:33:0) after 1m0s
[03:01:55.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:33:0)
[03:01:55.032] Timer tick, asking Buildbucket for the build status
[03:01:55.200] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:55.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:34:0) after 1m0s
[03:02:55.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:34:0)
[03:02:55.299] Timer tick, asking Buildbucket for the build status
[03:02:55.614] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:35:0) after 1m0s
[03:03:55.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:35:0)
[03:03:55.774] Timer tick, asking Buildbucket for the build status
[03:03:55.959] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:55.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:36:0) after 1m0s
[03:04:56.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:36:0)
[03:04:56.088] Timer tick, asking Buildbucket for the build status
[03:04:56.419] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:56.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:37:0) after 1m0s
[03:05:56.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:37:0)
[03:05:56.719] Timer tick, asking Buildbucket for the build status
[03:05:56.913] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:56.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:38:0) after 1m0s
[03:06:57.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:38:0)
[03:06:57.060] Timer tick, asking Buildbucket for the build status
[03:06:57.144] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:57.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:39:0) after 1m0s
[03:07:57.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:39:0)
[03:07:57.229] Timer tick, asking Buildbucket for the build status
[03:07:57.578] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:57.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:40:0) after 1m0s
[03:08:57.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:40:0)
[03:08:57.624] Timer tick, asking Buildbucket for the build status
[03:08:57.848] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:57.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:41:0) after 1m0s
[03:09:57.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:41:0)
[03:09:57.870] Timer tick, asking Buildbucket for the build status
[03:09:57.957] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:57.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:42:0) after 1m0s
[03:10:57.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:42:0)
[03:10:57.958] Timer tick, asking Buildbucket for the build status
[03:10:58.399] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:58.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:43:0) after 1m0s
[03:11:58.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:43:0)
[03:11:58.471] Timer tick, asking Buildbucket for the build status
[03:11:58.769] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:58.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:44:0) after 1m0s
[03:12:58.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:44:0)
[03:12:58.880] Timer tick, asking Buildbucket for the build status
[03:12:59.428] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:59.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:45:0) after 1m0s
[03:13:59.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:45:0)
[03:13:59.527] Timer tick, asking Buildbucket for the build status
[03:13:59.698] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:59.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:46:0) after 1m0s
[03:14:59.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:46:0)
[03:14:59.707] Timer tick, asking Buildbucket for the build status
[03:15:02.025] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:02.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:47:0) after 1m0s
[03:16:02.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:47:0)
[03:16:02.751] Timer tick, asking Buildbucket for the build status
[03:16:02.877] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:02.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:48:0) after 1m0s
[03:17:03.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:48:0)
[03:17:03.008] Timer tick, asking Buildbucket for the build status
[03:17:03.245] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:03.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:49:0) after 1m0s
[03:18:03.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:49:0)
[03:18:03.275] Timer tick, asking Buildbucket for the build status
[03:18:03.399] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:03.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:50:0) after 1m0s
[03:19:03.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:50:0)
[03:19:03.591] Timer tick, asking Buildbucket for the build status
[03:19:03.710] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:03.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:51:0) after 1m0s
[03:20:03.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:51:0)
[03:20:03.888] Timer tick, asking Buildbucket for the build status
[03:20:04.043] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:04.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:52:0) after 1m0s
[03:21:04.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:52:0)
[03:21:04.301] Timer tick, asking Buildbucket for the build status
[03:21:04.412] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:04.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:53:0) after 1m0s
[03:22:04.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:53:0)
[03:22:04.631] Timer tick, asking Buildbucket for the build status
[03:22:05.638] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:05.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:54:0) after 1m0s
[03:23:05.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:54:0)
[03:23:05.713] Timer tick, asking Buildbucket for the build status
[03:23:06.177] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:06.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:55:0) after 1m0s
[03:24:06.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:55:0)
[03:24:06.200] Timer tick, asking Buildbucket for the build status
[03:24:06.355] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:06.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:56:0) after 1m0s
[03:25:06.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:56:0)
[03:25:06.381] Timer tick, asking Buildbucket for the build status
[03:25:06.475] Build 8856897825068867600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:06.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022327887063868912:57:0) after 1m0s
[03:25:57.459] Received PubSub notification, asking Buildbucket for the build status
[03:25:57.631] Build 8856897825068867600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:25:57.631] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611804356411449",
"created_by": "project:chromium",
"created_ts": "1611801043405804",
"failure_reason": "BUILD_FAILURE",
"id": "8856897825068867600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a74a2c5df89b3141a2f16877d19f59c17ddcad49\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}, \"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\": \"a74a2c5df89b3141a2f16877d19f59c17ddcad49\", \"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-207-xtuo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44959, \"got_angle_revision\": \"52afb6f17cbd13a290c2798b565067f47d47af86\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"a74a2c5df89b3141a2f16877d19f59c17ddcad49\", \"got_revision_cp\": \"refs/heads/master@{#847937}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"54a4e0518d25fab76af6ec1d99b860b0b53b47cf\", \"got_v8_revision_cp\": \"refs/heads/9.0.90@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\", \"prev_got_revision\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\", \"prev_got_revision_cp\": \"refs/heads/master@{#847908}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a74a2c5df89b3141a2f16877d19f59c17ddcad49\", \"swarm_hashes_refs/heads/master(at){#847937}_without_patch\": {\"absl_hardening_tests\": \"79ca62d4d709ee137638c2a36b6b25fc55d1f9b4\", \"accessibility_unittests\": \"83c4ba7380e621445921237bf473a31f354e927c\", \"angle_unittests\": \"c03e19e2279ab8657dc4a86ed1043efa3c77cfbb\", \"app_list_unittests\": \"f146ee8151a9a16ccd6496307203f8bedee94df3\", \"app_shell_unittests\": \"e3d3d2bda7de8587c3f5d52579c5829e8d7ee4b3\", \"ash_unittests\": \"5869839420dea5f2f0da320a76c75d18f4d53053\", \"aura_unittests\": \"522cc3516671a7814b2707d165555804fb1ff5cc\", \"base_unittests\": \"124f7fcaf732f563f1cb6f9f85f8a412368780c1\", \"base_util_unittests\": \"9291099af79e257b346f4b20d97ca845076c1794\", \"blink_common_unittests\": \"d9d2adaa88475ab625acd635319812a9e267e1ea\", \"blink_fuzzer_unittests\": \"039f43908a872c1e08af107f9e7571df307c3ae0\", \"blink_heap_unittests\": \"d1b472536446ac164ea92b50ad437626d7e14bbb\", \"blink_platform_unittests\": \"47fea98f6f4df67f5730e7b10e445360bf21b83b\", \"blink_unittests\": \"b1df1803e2217c9b55df194f7e9217227e49fa0e\", \"boringssl_crypto_tests\": \"d0ff0551a485c92e279be7c2f89a20ed4c444495\", \"boringssl_ssl_tests\": \"b10e52dc8ffec7e5fb9bc986b83dbdbba62a5998\", \"browser_tests\": \"c2aa78139dab91de94289b0babb559ec0420bdc9\", \"capture_unittests\": \"5dffffac3df671fd18dd950e9d0eb430e8c3a15f\", \"cast_unittests\": \"e9e54530b033f20efba3ef29b4c187d51f336eb3\", \"cc_unittests\": \"ce05971fde382b6ac063cf9e202a620299945830\", \"chrome_app_unittests\": \"e18cc032c4dc97919a8b339751a38445de6abd79\", \"chromedriver_unittests\": \"8df2976213835f5c49b995aff8d94dcc97653ad3\", \"chromeos_components_unittests\": \"9825d79ccc262bf5b40777bd5b045191a24f7561\", \"chromeos_unittests\": \"234cf101e03c6dd43a509337eac1e3b65c4edf69\", \"components_browsertests\": \"5a3d1acb2031b084766fd30448b2722c555135cd\", \"components_unittests\": \"a4138ffbbc66e9afa519122946dcfa2e5d0ebc28\", \"compositor_unittests\": \"a298416adc573e8af859aebff6073c3a7e74a989\", \"content_browsertests\": \"1e0af3fdd89c2d2fb2056dab2c5c96be7cc8628f\", \"content_unittests\": \"b3404ff6d0af2accad26849cc474bb581dae0e86\", \"crashpad_tests\": \"631620a423690a8317e7ee8e3dcda50725f60730\", \"crypto_unittests\": \"dccedc247e6b8aaaa616962889fe758cb3bf677f\", \"dbus_unittests\": \"f62b547eec2348ce38c0ec9b0c4037c97c05432b\", \"device_unittests\": \"346268d2cb8eb4e9a9a0e808e0e3648ab24b67a9\", \"display_unittests\": \"2c74cdc8e662f22df33d17890123ae69984be0f5\", \"events_unittests\": \"93251ff6a9dbbeb8602bb4123362af6a09d0ebed\", \"exo_unittests\": \"b446b23e942fdb45ea5a41ed54575af99cfeda43\", \"extensions_browsertests\": \"3b00d63445c4038cc2c9f1cb0e93cf02214e8ed8\", \"extensions_unittests\": \"9f61ca52fcfae9813019fc7fc0bd9389889c12f8\", \"filesystem_service_unittests\": \"5ace3c146e459cfc046d927f3fd223d86130db48\", \"gcm_unit_tests\": \"2a5c0f862ed8b989aa01a7f8c68c643d24c9a977\", \"gfx_unittests\": \"06ce5d87d55ffd5002fb73a371855ae86af308a6\", \"gin_unittests\": \"d547dc10745c3c9f21213241c3cc2718969727b6\", \"gl_unittests_ozone\": \"729679edec0e56d9ca350f726be4c500ffb530d4\", \"google_apis_unittests\": \"92614f8b0176d4f38a324ff4797c8347b03d4804\", \"gpu_unittests\": \"43d620c402a34a9e48bbf687d6272e43f171be8a\", \"gwp_asan_unittests\": \"4faf94f3d52341b302d8974c9c906b1977f3e7e7\", \"interactive_ui_tests\": \"6074381eb3cc982510a2ea122be080c6013c4ea7\", \"ipc_tests\": \"a6bc7a6dc6e51e7c04bcd42ed549ebec08e9e03b\", \"jingle_unittests\": \"cc9278a5fb01528ea308f235acf73164a2f81c5b\", \"keyboard_unittests\": \"9b6ff391dbad0287a1aa328d555c8316654e0a95\", \"latency_unittests\": \"9b7b1be6eea0dbcf87bfc4cd50a2fb7ff00f12e3\", \"libjingle_xmpp_unittests\": \"05cb80a3e1a2970c280d438885ad3e7a727c8253\", \"liburlpattern_unittests\": \"c6b77f5783f7e946258beb0e51fa488301aa94a3\", \"media_blink_unittests\": \"d50524fef171a901d6250322cd634240fba791dd\", \"media_unittests\": \"be7a2fa2c138066d1ed43ed73117cd6035557ab9\", \"message_center_unittests\": \"a509d4bcdba8d62f3b57059783ac957ada508d3d\", \"midi_unittests\": \"aaeddf8fb562cc2928ea7c7b48047ba7a499f122\", \"mojo_core_unittests\": \"5eff85e14f9dac28ba8118f953875dcfb455963f\", \"mojo_unittests\": \"96e9102dc5b94140916a70e9753312848350c951\", \"nacl_helper_nonsfi_unittests\": \"120c9db1b8fbc24701aa07c07cdc7a9b46b1c410\", \"nacl_loader_unittests\": \"a95f53861f54127e194c344bb41983485b12358d\", \"native_theme_unittests\": \"a9763921227de428e59a903d62ddb3ded056e183\", \"net_unittests\": \"044821b9893de9092be639189f9b85473cf74ff7\", \"ozone_gl_unittests\": \"44f7a9d885bd859a0bc9249f0474452fad77e17f\", \"ozone_unittests\": \"bc9ca686d7ef750d4bb28a86ab24a48f485a4879\", \"ozone_x11_unittests\": \"e775aa3c1f82e098afde32755ce93b365a3c2caa\", \"pdf_unittests\": \"dc81979c82b20f5d06cca02af1feaaeb411e941e\", \"perfetto_unittests\": \"effd3463ac6b47380b84fc3e0958825b34f08090\", \"ppapi_unittests\": \"f7de145217836c2df94870c4791e13f318676ba8\", \"printing_unittests\": \"039425508df11da2df38537ac0dbe6a876c79404\", \"remoting_unittests\": \"5bd891defb9a5b8b9886f1e7d1f1198a0a9d37fd\", \"sandbox_linux_unittests\": \"7b75156a3a9ef08933299bf1d4d00b47806918f3\", \"service_manager_unittests\": \"40d84fb0f7f1b0b3d569df59e09756efcc8ae49d\", \"services_unittests\": \"6cbdf3b2273aef677625bff74b13a7ee355b16ae\", \"shell_dialogs_unittests\": \"76b2ab3d139a92f8462c8f909ec25ed6e17c3492\", \"shell_encryption_unittests\": \"8a3de6477c9e66974a3842db7655c961fb6c707e\", \"skia_unittests\": \"ee291066e3feebff8ffe256b042abc2094222e92\", \"snapshot_unittests\": \"c3b2e9d55da396163d646c9229631f1db4a7da27\", \"sql_unittests\": \"57ba03ecf28c6366d481680161eec246f952e2ab\", \"storage_unittests\": \"6accc7b183a0c4ff253d28000942a4b504330116\", \"sync_integration_tests\": \"0d69068b9e6aa45ca89534b84139a7a02571bff8\", \"traffic_annotation_auditor_unittests\": \"15806a4ca64efeb4308be6a59a42e129a7aea5fc\", \"ui_base_unittests\": \"97ad80dfb8311ffb13c24a7a1d484fa146ce9391\", \"ui_chromeos_unittests\": \"68fac01434c7f8408c7643168640b927bcb59983\", \"ui_touch_selection_unittests\": \"a1d924067afc89e94cf287c5c5c3912c2906ac1a\", \"unit_tests\": \"7d0ad3b5ac7b49ce53c20e50cb65cabdb741b539\", \"url_unittests\": \"cd0492f1f42dbe50fe062f947aaa4d903b9fda07\", \"usage_time_limit_unittests\": \"365d8cd85ec21a93272f3ee6d3b471df1b82ca57\", \"views_unittests\": \"9f62d2366f2bfcd1aab62e3560ab37dbd93216c1\", \"viz_unittests\": \"ee2380dc9510061ec96cff04f757a6573548eb34\", \"wayland_client_perftests\": \"5480bdff2cb5b8575c2bf81b3f3624e310fce3c9\", \"wm_unittests\": \"262216b1e25f965b45c69424d145ab86519d0c7a\", \"wtf_unittests\": \"27a01ba9246ee33ffbe67c98a8c0972c6bf82b6e\", \"zlib_unittests\": \"40228d23436accefd9b2718da127fa346006af78\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0fb1f456593ae2600bf9c9616e0e5dcc3c87ffc9d4d6fb93e9350aac8ae4516c_v2\", \"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_74d362f4b8458dd9d0e97927161f205f1d3617f2c5338e4089191185ec3041e6_v2\", \"builder_7f949073f7e904bb9563d7870e146ee8e8e8f26011cb03330fcb60d1fc06c798_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_f0afc432815c064941e1e5f998abb72673d4068febb53723f4aedad56e1b9c62_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-207-xtuo\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611801136859289",
"status": "COMPLETED",
"status_changed_ts": "1611801137179055",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44959",
"builder:linux-chromeos-rel",
"buildset:commit/git/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a74a2c5df89b3141a2f16877d19f59c17ddcad49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022327887063868912",
"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/8856897825068867600/+/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:515fa5acd741ca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611804357145715",
"url": "https://ci.chromium.org/b/8856897825068867600",
"utcnow_ts": "1611804357620123"
}
}
[03:25:57.631] Invocation finished in 55m15.512190945s with status FAILED