[22:41:52.926] New invocation is queued and will start shortly
[22:41:54.015] Starting the invocation (attempt 1)
[22:41:54.066] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:54.066] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:54.066] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028593473885772672",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"89582373a3ef2035754713d803298c976059fef3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028593473885772672",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/89582373a3ef2035754713d803298c976059fef3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89582373a3ef2035754713d803298c976059fef3",
"gitiles_ref:refs/heads/master"
]
}
[22:41:54.873] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605825714178924",
"id": "8863163411888244592",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89582373a3ef2035754713d803298c976059fef3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605825714514613",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43006",
"builder:linux-chromeos-rel",
"buildset:commit/git/89582373a3ef2035754713d803298c976059fef3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89582373a3ef2035754713d803298c976059fef3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028593473885772672",
"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/8863163411888244592/+/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": "1605825714514726",
"url": "https://ci.chromium.org/b/8863163411888244592",
"utcnow_ts": "1605825714864288"
}
}
[22:41:54.873] Task URL: https://ci.chromium.org/b/8863163411888244592
[22:41:54.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:2:0) after 1m0s
[22:42:54.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:2:0)
[22:42:54.894] Timer tick, asking Buildbucket for the build status
[22:42:54.999] Build 8863163411888244592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:3:0) after 1m0s
[22:43:09.119] Received PubSub notification, asking Buildbucket for the build status
[22:43:09.450] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:55.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:3:0)
[22:43:55.098] Timer tick, asking Buildbucket for the build status
[22:43:55.193] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:55.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:5:0) after 1m0s
[22:44:55.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:5:0)
[22:44:55.239] Timer tick, asking Buildbucket for the build status
[22:44:55.741] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:55.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:6:0) after 1m0s
[22:45:55.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:6:0)
[22:45:55.907] Timer tick, asking Buildbucket for the build status
[22:45:56.273] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:56.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:7:0) after 1m0s
[22:46:56.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:7:0)
[22:46:56.495] Timer tick, asking Buildbucket for the build status
[22:46:56.897] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:56.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:8:0) after 1m0s
[22:47:57.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:8:0)
[22:47:57.058] Timer tick, asking Buildbucket for the build status
[22:47:57.195] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:57.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:9:0) after 1m0s
[22:48:57.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:9:0)
[22:48:57.276] Timer tick, asking Buildbucket for the build status
[22:48:57.642] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:57.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:10:0) after 1m0s
[22:49:57.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:10:0)
[22:49:57.776] Timer tick, asking Buildbucket for the build status
[22:49:57.914] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:57.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:11:0) after 1m0s
[22:50:57.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:11:0)
[22:50:57.968] Timer tick, asking Buildbucket for the build status
[22:50:58.051] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:58.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:12:0) after 1m0s
[22:51:58.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:12:0)
[22:51:58.132] Timer tick, asking Buildbucket for the build status
[22:51:58.254] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:58.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:13:0) after 1m0s
[22:52:58.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:13:0)
[22:52:58.298] Timer tick, asking Buildbucket for the build status
[22:52:58.364] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:58.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:14:0) after 1m0s
[22:53:58.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:14:0)
[22:53:58.447] Timer tick, asking Buildbucket for the build status
[22:53:58.511] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:58.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:15:0) after 1m0s
[22:54:58.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:15:0)
[22:54:58.621] Timer tick, asking Buildbucket for the build status
[22:54:58.685] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:58.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:16:0) after 1m0s
[22:55:58.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:16:0)
[22:55:58.815] Timer tick, asking Buildbucket for the build status
[22:55:59.004] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:59.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:17:0) after 1m0s
[22:56:58.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:17:0)
[22:56:58.987] Timer tick, asking Buildbucket for the build status
[22:56:59.286] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:59.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:18:0) after 1m0s
[22:57:59.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:18:0)
[22:57:59.308] Timer tick, asking Buildbucket for the build status
[22:57:59.449] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:59.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:19:0) after 1m0s
[22:58:59.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:19:0)
[22:58:59.520] Timer tick, asking Buildbucket for the build status
[22:58:59.605] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:59.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:20:0) after 1m0s
[22:59:59.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:20:0)
[22:59:59.688] Timer tick, asking Buildbucket for the build status
[22:59:59.837] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:59.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:21:0) after 1m0s
[23:00:59.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:21:0)
[23:00:59.898] Timer tick, asking Buildbucket for the build status
[23:00:59.973] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:59.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:22:0) after 1m0s
[23:02:00.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:22:0)
[23:02:00.136] Timer tick, asking Buildbucket for the build status
[23:02:00.552] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:00.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:23:0) after 1m0s
[23:03:00.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:23:0)
[23:03:00.563] Timer tick, asking Buildbucket for the build status
[23:03:00.815] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:00.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:24:0) after 1m0s
[23:04:01.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:24:0)
[23:04:01.305] Timer tick, asking Buildbucket for the build status
[23:04:01.437] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:01.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:25:0) after 1m0s
[23:05:01.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:25:0)
[23:05:01.523] Timer tick, asking Buildbucket for the build status
[23:05:01.697] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:01.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:26:0) after 1m0s
[23:06:01.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:26:0)
[23:06:01.742] Timer tick, asking Buildbucket for the build status
[23:06:02.168] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:02.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:27:0) after 1m0s
[23:07:02.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:27:0)
[23:07:02.271] Timer tick, asking Buildbucket for the build status
[23:07:02.445] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:02.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:28:0) after 1m0s
[23:08:02.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:28:0)
[23:08:02.510] Timer tick, asking Buildbucket for the build status
[23:08:03.865] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:03.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:29:0) after 1m0s
[23:09:03.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:29:0)
[23:09:03.983] Timer tick, asking Buildbucket for the build status
[23:09:04.105] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:04.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:30:0) after 1m0s
[23:10:04.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:30:0)
[23:10:04.148] Timer tick, asking Buildbucket for the build status
[23:10:04.287] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:04.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:31:0) after 1m0s
[23:11:04.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:31:0)
[23:11:04.356] Timer tick, asking Buildbucket for the build status
[23:11:04.442] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:04.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:32:0) after 1m0s
[23:12:04.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:32:0)
[23:12:04.575] Timer tick, asking Buildbucket for the build status
[23:12:04.708] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:04.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:33:0) after 1m0s
[23:13:04.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:33:0)
[23:13:04.786] Timer tick, asking Buildbucket for the build status
[23:13:04.858] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:04.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:34:0) after 1m0s
[23:14:04.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:34:0)
[23:14:04.958] Timer tick, asking Buildbucket for the build status
[23:14:05.122] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:05.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:35:0) after 1m0s
[23:15:05.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:35:0)
[23:15:05.219] Timer tick, asking Buildbucket for the build status
[23:15:05.286] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:05.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:36:0) after 1m0s
[23:16:05.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:36:0)
[23:16:05.324] Timer tick, asking Buildbucket for the build status
[23:16:05.497] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:05.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:37:0) after 1m0s
[23:17:05.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:37:0)
[23:17:05.580] Timer tick, asking Buildbucket for the build status
[23:17:08.607] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:38:0) after 1m0s
[23:18:08.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:38:0)
[23:18:08.643] Timer tick, asking Buildbucket for the build status
[23:18:08.930] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:08.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:39:0) after 1m0s
[23:19:08.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:39:0)
[23:19:08.944] Timer tick, asking Buildbucket for the build status
[23:19:09.641] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:09.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:40:0) after 1m0s
[23:20:09.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:40:0)
[23:20:09.670] Timer tick, asking Buildbucket for the build status
[23:20:09.767] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:41:0) after 1m0s
[23:21:09.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:41:0)
[23:21:09.850] Timer tick, asking Buildbucket for the build status
[23:21:09.970] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:09.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:42:0) after 1m0s
[23:22:10.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:42:0)
[23:22:10.052] Timer tick, asking Buildbucket for the build status
[23:22:10.170] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:43:0) after 1m0s
[23:23:10.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:43:0)
[23:23:10.285] Timer tick, asking Buildbucket for the build status
[23:23:10.508] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:10.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:44:0) after 1m0s
[23:24:10.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:44:0)
[23:24:10.507] Timer tick, asking Buildbucket for the build status
[23:24:10.618] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:45:0) after 1m0s
[23:25:10.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:45:0)
[23:25:10.676] Timer tick, asking Buildbucket for the build status
[23:25:11.060] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:11.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:46:0) after 1m0s
[23:26:11.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:46:0)
[23:26:11.123] Timer tick, asking Buildbucket for the build status
[23:26:11.237] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:11.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:47:0) after 1m0s
[23:27:11.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:47:0)
[23:27:11.281] Timer tick, asking Buildbucket for the build status
[23:27:11.522] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:11.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:48:0) after 1m0s
[23:28:11.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:48:0)
[23:28:11.516] Timer tick, asking Buildbucket for the build status
[23:28:11.625] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:11.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:49:0) after 1m0s
[23:29:11.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:49:0)
[23:29:11.735] Timer tick, asking Buildbucket for the build status
[23:29:11.879] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:11.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:50:0) after 1m0s
[23:30:11.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:50:0)
[23:30:11.966] Timer tick, asking Buildbucket for the build status
[23:30:13.251] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:51:0) after 1m0s
[23:31:13.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:51:0)
[23:31:13.353] Timer tick, asking Buildbucket for the build status
[23:31:13.452] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:13.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:52:0) after 1m0s
[23:32:13.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:52:0)
[23:32:13.447] Timer tick, asking Buildbucket for the build status
[23:32:13.627] Build 8863163411888244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:13.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028593473885772672:53:0) after 1m0s
[23:32:19.998] Received PubSub notification, asking Buildbucket for the build status
[23:32:20.074] Build 8863163411888244592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:32:20.074] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605828738307728",
"created_by": "project:chromium",
"created_ts": "1605825714178924",
"failure_reason": "BUILD_FAILURE",
"id": "8863163411888244592",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89582373a3ef2035754713d803298c976059fef3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- LazyBackgroundPageApiTest.Messaging\\n\\n- ExtensionSettingsApiTest.ExtensionsSchemas\"}, \"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\": \"89582373a3ef2035754713d803298c976059fef3\", \"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-122-1tau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43006, \"got_angle_revision\": \"353770e13361f893f63cd1567db48417d65c267e\", \"got_dawn_revision\": \"1a14e7e0244114fe737caf46e3a2690355aceafe\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"89582373a3ef2035754713d803298c976059fef3\", \"got_revision_cp\": \"refs/heads/master@{#829404}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89582373a3ef2035754713d803298c976059fef3\", \"swarm_hashes_refs/heads/master(at){#829404}_without_patch\": {\"absl_hardening_tests\": \"3c072f49790a6981b2192cdeba3ebef0425bac62\", \"accessibility_unittests\": \"6d4f047ed18285cedc04508c715e797846d7f2fd\", \"angle_unittests\": \"e279ba369572270214eb0858e609a7b44a9fed6f\", \"app_list_unittests\": \"92e9a96d45fcbabcc93b952f9014ea663df84a35\", \"app_shell_unittests\": \"148801b2710479bb934b15897b866c4e5ea4a000\", \"ash_unittests\": \"b53915bb1355bb4cb40405b0c3d9ab662c03ed5a\", \"aura_unittests\": \"c67682f8fdfdd6464cb3247bc7ad16250ed17869\", \"base_unittests\": \"0cd85f9c90198bf08d109a94a6f294e8eaee26a7\", \"base_util_unittests\": \"18e98cdb8cf0ee8f3f6aa2bcb8a9144becbd60fb\", \"blink_common_unittests\": \"b98a4a0c8aab141b9518e8ab0a79dd33f17d50c5\", \"blink_fuzzer_unittests\": \"763220ac9cd6ac49dabe47bbc30efab10f0a0153\", \"blink_heap_unittests\": \"804200a3fc0cd7c43b3c00e88c42c8c53f7ee2d3\", \"blink_platform_unittests\": \"82cd39cb7f3e7f481524b9482a11f3d54814d931\", \"blink_unittests\": \"61f2add79c857eaf558ff185090d89da542adce1\", \"boringssl_crypto_tests\": \"35f76282e19ec2de98c58dc9640620120052f4f9\", \"boringssl_ssl_tests\": \"b5b23e5ac9a496b3dd985e601a6389b66c852996\", \"browser_tests\": \"f7b40f879a4f7c8cf033e7c786492031c02ad175\", \"capture_unittests\": \"2b77de58039b02d5d7d3b8f1a80d7e298055f425\", \"cast_unittests\": \"5ae19d1ba784f6ce80bb809896524882d308452c\", \"cc_unittests\": \"8fb86330a5d4b4e06aecbb00644aca892e480339\", \"chrome_app_unittests\": \"57add1c889051213d410fd0d6906f6afc664076e\", \"chromedriver_unittests\": \"407ec7fdfaab962985594ac5ad0b66d13a826c1c\", \"chromeos_components_unittests\": \"ef2b4a3d519bc8e61568ccf2a8512077f195ca85\", \"chromeos_unittests\": \"c592f107d27bd95a3ed8d7f414f4c67b9434ae8c\", \"components_browsertests\": \"f9adb3a6a7ac2fa6f7437430d561eae39f7b63b5\", \"components_unittests\": \"15decd1370258979553cf73562ce225687642359\", \"compositor_unittests\": \"58885991e42f8a58cf68a7696b6d728148248417\", \"content_browsertests\": \"b58a9ef38e5ce81219379bbbbde49190c8c48335\", \"content_unittests\": \"552b3e762325fb3dcc96a85c79e168c0f3ba9076\", \"crashpad_tests\": \"47006d576e25f246f95984e35bf8766048199fdc\", \"crypto_unittests\": \"d8270038b8f252c9c7ca0de22644060910d0b380\", \"dbus_unittests\": \"a7e2cc3f4bc8b9ee26e223a5445e9c1b87a16104\", \"device_unittests\": \"cdee51673f2e2cd48d5596872e0198641e4d94fa\", \"display_unittests\": \"b10363dd0b010aced5d0d986717be526a01d6133\", \"events_unittests\": \"d9de910b9bdb8d73ecd20cb1b2b3ddd8a0c880e9\", \"exo_unittests\": \"899f27dfe88a7219d6a37fb1f8c1fb03f074abc4\", \"extensions_browsertests\": \"6a1ad96ae9565c52c3dbaf5783f1c05ff680a5d1\", \"extensions_unittests\": \"da3b5d23508eef9b62742e6c65e3dd13fc7ed48b\", \"filesystem_service_unittests\": \"1308e30a14648527c7776856300d3532d5aca2af\", \"gcm_unit_tests\": \"dc05f6bafcd8bc494644bb7d30c2aff0342f2a91\", \"gfx_unittests\": \"dd26abaad46b5aa3fdcb5487bc1a013eb2a62615\", \"gin_unittests\": \"5da1b22668bb2d0dc6185853b4f01242d3f35451\", \"gl_unittests_ozone\": \"9b59efeed373e20e88a82ff03b8c628c7819a1a7\", \"google_apis_unittests\": \"280bf01fec015d1b785d972d6e041e9fbf8f014c\", \"gpu_unittests\": \"31f60d9676575410f9ef6c5eba8ed47e685275d4\", \"gwp_asan_unittests\": \"7c228ec6cb39e7f9edeeea892941ecf0f8b7d7b2\", \"interactive_ui_tests\": \"9577717791ecf457ec8301b9cddfaee5f4339e59\", \"ipc_tests\": \"1ea57603c1898e23897cb344c80b2983b1d589ff\", \"jingle_unittests\": \"c8ae137667cd54902cc39ea3795a1d56ef1d7c28\", \"keyboard_unittests\": \"40f8e8d1a75b43be9b69ead71c5ff8a74f089166\", \"latency_unittests\": \"57a9660699a6949496796f1b857c9a2ad0f24835\", \"libjingle_xmpp_unittests\": \"b0fae738832c0f34c7bdb82f135b3f93d2c90e6d\", \"liburlpattern_unittests\": \"ace44bc0d124a6b2b546a5478b1a555bfc90c626\", \"media_blink_unittests\": \"f4e7ca852fd652d4e5a7493a90d76c932db502d2\", \"media_unittests\": \"df8f10d2265f367984d6db76336ac1a0b3117f82\", \"message_center_unittests\": \"b0fff8cf1408008588f5e42306b279402db3872b\", \"midi_unittests\": \"8c95191bc08f6cbc3f743398fb92b6030b383d94\", \"mojo_core_unittests\": \"5ebcc089ba4f80d3f307c1f71e2a32b45cbb5332\", \"mojo_unittests\": \"c2c2289099d37cd3fec6044d8f1a035242d9cc5f\", \"nacl_helper_nonsfi_unittests\": \"de663b19db030cc6479bcc2044c7411d6ade1a7d\", \"nacl_loader_unittests\": \"878f9e7063ab4b83ce5009ccb6df95275383c6bb\", \"native_theme_unittests\": \"0cf1a7c105d0caac39d2c8f3cd83bcc1ca8ab196\", \"net_unittests\": \"402e53f8e34edd30f06b1470f0f0fc5da3f30d43\", \"ozone_gl_unittests\": \"f1bc40e2e83b727bbc7a7c70ef12944bb1acccbc\", \"ozone_unittests\": \"04a7a631288a955c1b7f8d4be8e8a41095f9d5d7\", \"ozone_x11_unittests\": \"82d0b91afafd9d925d3233ac9c03a9e5bd48a38b\", \"pdf_unittests\": \"657f38e1847d38d219d996865ce414ac4a083404\", \"perfetto_unittests\": \"b9382344a7ee6935359f4e42df82db7bd47bb33b\", \"ppapi_unittests\": \"2e3673b618eab0f0eb9024a402682949100f53d4\", \"printing_unittests\": \"7b0c8bc87b34c5e43bfb6a21b3ad46e0167e6bbb\", \"remoting_unittests\": \"9e4f8d993b054cc56398850f6502ea6d2c6b3374\", \"sandbox_linux_unittests\": \"0c4fafbeb1a44e0e9a23c57188dd2f36ca30bc49\", \"service_manager_unittests\": \"091c8ecb7a216637251d6468e20db69050465fa2\", \"services_unittests\": \"9e19be240066e46d3ac7b169c64b0863081833bd\", \"shell_dialogs_unittests\": \"7df0579fc6a0552ddb0aa40564405b67a09dee66\", \"skia_unittests\": \"89230b145ca06881dd2d58bfcb4dd06bdd17c539\", \"snapshot_unittests\": \"2066b1ecf7bf3fd753df98911852a1f783f6f698\", \"sql_unittests\": \"1dc4ac881bbb7512fc13a9fc80e2be265a5693f4\", \"storage_unittests\": \"75a893791f718a123b2d0e98980b6325fe181d8e\", \"sync_integration_tests\": \"aa1f7a1a05ecfaafeb9ab3201746cdeb76d65c44\", \"traffic_annotation_auditor_unittests\": \"7de617ff8a8be9e818e51e8a45d2f9fb7c7ccf42\", \"ui_base_unittests\": \"755fe381611935eb4449bd239c3b25d3c3a050ea\", \"ui_chromeos_unittests\": \"e284ccc827d7d954df808118f7ab8ff55a0ca7de\", \"ui_touch_selection_unittests\": \"c2253cb10d2a5efce4e6657aaab16b6f3f53030f\", \"unit_tests\": \"633c8878eb0378399eb0a38a12dd0049f6f57e41\", \"url_unittests\": \"258c577858f8411be071bb47656e94a0ec4ca462\", \"usage_time_limit_unittests\": \"0fd69c4ff9562c021d8539a556860d86129c0610\", \"views_unittests\": \"d439ce1dbc7a99616bae60f72ce3c8b32fb30d4a\", \"viz_unittests\": \"1a1132b5ce37ace0270b2acea95131756face34d\", \"wayland_client_perftests\": \"9f02d9db8fb1040f52d04ca4488e263e831469de\", \"wm_unittests\": \"b10bb5cc2d69d0beaa48307a1633d9c01d1340d2\", \"wtf_unittests\": \"c6933ceeaea3592432f314a5de9f8f18371d110b\", \"zlib_unittests\": \"27ad186ee6102cc439a3aabb72b5c364119f1be8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e22c04085d4f23fdc95f18e428a3d660f7cf1c501a8c991d94e3298ba2ece61_v2\", \"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_7ac44f9f43d6a2d32c05bd5b65ce082467e4e76030f98d79d844856060c02531_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_b492a6a54599c40c9efefa6e5d1cdf990fad3125ae5ed8fb1c6f132ff9c1d569_v2\", \"builder_be6033c31ad7fe993e51aceefec32c9781d9cb2aa7f3fe71df24045d7e98805a_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_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-122-1tau\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5637-4d8b832\"], \"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- LazyBackgroundPageApiTest.Messaging\\n\\n- ExtensionSettingsApiTest.ExtensionsSchemas\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605825787247088",
"status": "COMPLETED",
"status_changed_ts": "1605825787932455",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43006",
"builder:linux-chromeos-rel",
"buildset:commit/git/89582373a3ef2035754713d803298c976059fef3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89582373a3ef2035754713d803298c976059fef3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028593473885772672",
"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/8863163411888244592/+/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:4ffb7d64e50cae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605828738787992",
"url": "https://ci.chromium.org/b/8863163411888244592",
"utcnow_ts": "1605828740064663"
}
}
[23:32:20.074] Invocation finished in 50m27.184286435s with status FAILED