[12:47:04.870] New invocation is queued and will start shortly
[12:47:05.953] Starting the invocation (attempt 1)
[12:47:05.988] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:47:05.988] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:47:05.988] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022198510891287856",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022198510891287856",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"gitiles_ref:refs/heads/master"
]
}
[12:47:06.641] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611924426079564",
"id": "8856768448957693200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611924426460459",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44999",
"builder:linux-chromeos-rel",
"buildset:commit/git/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022198510891287856",
"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/8856768448957693200/+/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": "1611924426460570",
"url": "https://ci.chromium.org/b/8856768448957693200",
"utcnow_ts": "1611924426631387"
}
}
[12:47:06.641] Task URL: https://ci.chromium.org/b/8856768448957693200
[12:47:06.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:2:0) after 1m0s
[12:47:22.305] Received PubSub notification, asking Buildbucket for the build status
[12:47:22.594] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:06.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:2:0)
[12:48:06.617] Timer tick, asking Buildbucket for the build status
[12:48:06.766] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:06.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:4:0) after 1m0s
[12:49:06.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:4:0)
[12:49:06.811] Timer tick, asking Buildbucket for the build status
[12:49:06.937] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:06.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:5:0) after 1m0s
[12:50:07.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:5:0)
[12:50:07.032] Timer tick, asking Buildbucket for the build status
[12:50:07.116] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:07.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:6:0) after 1m0s
[12:51:07.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:6:0)
[12:51:07.469] Timer tick, asking Buildbucket for the build status
[12:51:07.640] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:07.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:7:0) after 1m0s
[12:52:07.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:7:0)
[12:52:07.902] Timer tick, asking Buildbucket for the build status
[12:52:08.637] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:08.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:8:0) after 1m0s
[12:53:08.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:8:0)
[12:53:08.693] Timer tick, asking Buildbucket for the build status
[12:53:09.132] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:09.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:9:0) after 1m0s
[12:54:09.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:9:0)
[12:54:09.219] Timer tick, asking Buildbucket for the build status
[12:54:09.320] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:09.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:10:0) after 1m0s
[12:55:09.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:10:0)
[12:55:09.411] Timer tick, asking Buildbucket for the build status
[12:55:09.577] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:09.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:11:0) after 1m0s
[12:56:09.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:11:0)
[12:56:09.700] Timer tick, asking Buildbucket for the build status
[12:56:09.761] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:09.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:12:0) after 1m0s
[12:57:09.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:12:0)
[12:57:09.871] Timer tick, asking Buildbucket for the build status
[12:57:09.965] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:09.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:13:0) after 1m0s
[12:58:10.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:13:0)
[12:58:10.006] Timer tick, asking Buildbucket for the build status
[12:58:10.093] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:10.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:14:0) after 1m0s
[12:59:10.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:14:0)
[12:59:10.083] Timer tick, asking Buildbucket for the build status
[12:59:10.335] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:10.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:15:0) after 1m0s
[13:00:10.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:15:0)
[13:00:10.381] Timer tick, asking Buildbucket for the build status
[13:00:10.480] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:10.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:16:0) after 1m0s
[13:01:10.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:16:0)
[13:01:10.637] Timer tick, asking Buildbucket for the build status
[13:01:10.727] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:10.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:17:0) after 1m0s
[13:02:10.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:17:0)
[13:02:10.833] Timer tick, asking Buildbucket for the build status
[13:02:10.939] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:10.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:18:0) after 1m0s
[13:03:11.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:18:0)
[13:03:11.055] Timer tick, asking Buildbucket for the build status
[13:03:11.235] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:11.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:19:0) after 1m0s
[13:04:11.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:19:0)
[13:04:11.252] Timer tick, asking Buildbucket for the build status
[13:04:11.316] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:11.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:20:0) after 1m0s
[13:05:11.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:20:0)
[13:05:11.459] Timer tick, asking Buildbucket for the build status
[13:05:11.509] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:11.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:21:0) after 1m0s
[13:06:11.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:21:0)
[13:06:11.561] Timer tick, asking Buildbucket for the build status
[13:06:11.636] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:11.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:22:0) after 1m0s
[13:07:11.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:22:0)
[13:07:11.669] Timer tick, asking Buildbucket for the build status
[13:07:12.021] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:12.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:23:0) after 1m0s
[13:08:12.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:23:0)
[13:08:12.129] Timer tick, asking Buildbucket for the build status
[13:08:12.192] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:12.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:24:0) after 1m0s
[13:09:12.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:24:0)
[13:09:12.261] Timer tick, asking Buildbucket for the build status
[13:09:12.395] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:12.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:25:0) after 1m0s
[13:10:12.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:25:0)
[13:10:12.595] Timer tick, asking Buildbucket for the build status
[13:10:12.970] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:12.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:26:0) after 1m0s
[13:11:13.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:26:0)
[13:11:13.001] Timer tick, asking Buildbucket for the build status
[13:11:13.062] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:13.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:27:0) after 1m0s
[13:12:13.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:27:0)
[13:12:13.146] Timer tick, asking Buildbucket for the build status
[13:12:13.542] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:13.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:28:0) after 1m0s
[13:13:13.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:28:0)
[13:13:13.575] Timer tick, asking Buildbucket for the build status
[13:13:13.672] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:13.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:29:0) after 1m0s
[13:14:13.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:29:0)
[13:14:13.819] Timer tick, asking Buildbucket for the build status
[13:14:13.910] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:13.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:30:0) after 1m0s
[13:15:13.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:30:0)
[13:15:13.972] Timer tick, asking Buildbucket for the build status
[13:15:14.119] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:14.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:31:0) after 1m0s
[13:16:14.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:31:0)
[13:16:14.143] Timer tick, asking Buildbucket for the build status
[13:16:14.252] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:14.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:32:0) after 1m0s
[13:17:14.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:32:0)
[13:17:14.371] Timer tick, asking Buildbucket for the build status
[13:17:14.708] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:14.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:33:0) after 1m0s
[13:18:14.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:33:0)
[13:18:14.792] Timer tick, asking Buildbucket for the build status
[13:18:14.865] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:14.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:34:0) after 1m0s
[13:19:14.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:34:0)
[13:19:14.956] Timer tick, asking Buildbucket for the build status
[13:19:15.229] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:15.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:35:0) after 1m0s
[13:20:15.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:35:0)
[13:20:15.244] Timer tick, asking Buildbucket for the build status
[13:20:15.309] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:15.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:36:0) after 1m0s
[13:21:15.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:36:0)
[13:21:15.419] Timer tick, asking Buildbucket for the build status
[13:21:15.820] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:15.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:37:0) after 1m0s
[13:22:15.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:37:0)
[13:22:15.950] Timer tick, asking Buildbucket for the build status
[13:22:16.118] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:16.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:38:0) after 1m0s
[13:23:16.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:38:0)
[13:23:16.192] Timer tick, asking Buildbucket for the build status
[13:23:16.255] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:16.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:39:0) after 1m0s
[13:24:16.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:39:0)
[13:24:16.291] Timer tick, asking Buildbucket for the build status
[13:24:16.355] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:16.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:40:0) after 1m0s
[13:25:16.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:40:0)
[13:25:16.382] Timer tick, asking Buildbucket for the build status
[13:25:16.460] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:41:0) after 1m0s
[13:26:16.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:41:0)
[13:26:16.539] Timer tick, asking Buildbucket for the build status
[13:26:16.717] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:42:0) after 1m0s
[13:27:16.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:42:0)
[13:27:16.760] Timer tick, asking Buildbucket for the build status
[13:27:17.042] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:17.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:43:0) after 1m0s
[13:28:17.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:43:0)
[13:28:17.149] Timer tick, asking Buildbucket for the build status
[13:28:17.261] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:44:0) after 1m0s
[13:29:17.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:44:0)
[13:29:17.245] Timer tick, asking Buildbucket for the build status
[13:29:17.335] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:45:0) after 1m0s
[13:30:17.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:45:0)
[13:30:17.394] Timer tick, asking Buildbucket for the build status
[13:30:17.489] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:46:0) after 1m0s
[13:31:17.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:46:0)
[13:31:17.568] Timer tick, asking Buildbucket for the build status
[13:31:17.951] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:17.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:47:0) after 1m0s
[13:32:18.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:47:0)
[13:32:18.018] Timer tick, asking Buildbucket for the build status
[13:32:18.121] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:48:0) after 1m0s
[13:33:18.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:48:0)
[13:33:18.204] Timer tick, asking Buildbucket for the build status
[13:33:18.505] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:49:0) after 1m0s
[13:34:18.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:49:0)
[13:34:18.565] Timer tick, asking Buildbucket for the build status
[13:34:18.699] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:18.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:50:0) after 1m0s
[13:35:18.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:50:0)
[13:35:18.830] Timer tick, asking Buildbucket for the build status
[13:35:19.017] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:19.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:51:0) after 1m0s
[13:36:19.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:51:0)
[13:36:19.228] Timer tick, asking Buildbucket for the build status
[13:36:20.011] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:20.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:52:0) after 1m0s
[13:37:20.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:52:0)
[13:37:20.082] Timer tick, asking Buildbucket for the build status
[13:37:20.198] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:20.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:53:0) after 1m0s
[13:38:20.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:53:0)
[13:38:20.278] Timer tick, asking Buildbucket for the build status
[13:38:20.390] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:20.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:54:0) after 1m0s
[13:39:20.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:54:0)
[13:39:20.605] Timer tick, asking Buildbucket for the build status
[13:39:20.719] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:20.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:55:0) after 1m0s
[13:40:20.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:55:0)
[13:40:20.794] Timer tick, asking Buildbucket for the build status
[13:40:20.927] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:20.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:56:0) after 1m0s
[13:41:20.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:56:0)
[13:41:20.957] Timer tick, asking Buildbucket for the build status
[13:41:21.353] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:21.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:57:0) after 1m0s
[13:42:21.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:57:0)
[13:42:21.376] Timer tick, asking Buildbucket for the build status
[13:42:21.638] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:21.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:58:0) after 1m0s
[13:43:21.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:58:0)
[13:43:21.708] Timer tick, asking Buildbucket for the build status
[13:43:21.813] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:21.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:59:0) after 1m0s
[13:44:21.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:59:0)
[13:44:21.859] Timer tick, asking Buildbucket for the build status
[13:44:21.952] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:60:0) after 1m0s
[13:45:22.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:60:0)
[13:45:22.227] Timer tick, asking Buildbucket for the build status
[13:45:22.387] Build 8856768448957693200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:22.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022198510891287856:61:0) after 1m0s
[13:45:45.348] Received PubSub notification, asking Buildbucket for the build status
[13:45:45.416] Build 8856768448957693200: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:45:45.416] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611927944309392",
"created_by": "project:chromium",
"created_ts": "1611924426079564",
"failure_reason": "BUILD_FAILURE",
"id": "8856768448957693200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.ChromeVoxNextTabRecovery/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\": \"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\", \"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-150-xtob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44999, \"got_angle_revision\": \"e419222d82cff7286ccc368932bf0fbb5d31d9e9\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\", \"got_revision_cp\": \"refs/heads/master@{#848479}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"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\": \"f906c6c1194a339d3ed420e2442a69b6c8ed98a3\", \"swarm_hashes_refs/heads/master(at){#848479}_without_patch\": {\"absl_hardening_tests\": \"d825f247f0091f95fddebda7c01d12df354334cb\", \"accessibility_unittests\": \"6a5c655dd023c23d01515289481c8c63852644a2\", \"angle_unittests\": \"eeacc777022921205f3fad5fc804f30e332d2e21\", \"app_list_unittests\": \"9ee35dd4c57ea5f5784af4c5def5e269ff2c71cc\", \"app_shell_unittests\": \"7e482661cdea24797ab682f223f13d51bd16bccb\", \"ash_unittests\": \"2dda36ead8bb2cdf3e7b39a8f2ab01b736f255dc\", \"aura_unittests\": \"0c2a164627e17fb7176a80076c5586cded99c45a\", \"base_unittests\": \"037e1675ce8e3f7d66d9ec6aba3413582914c5da\", \"base_util_unittests\": \"241f2516f71a57259ebca8be6ea9b00f3387a40b\", \"blink_common_unittests\": \"1e1cb1f93acf50171977d28c426c47f5c16f34b8\", \"blink_fuzzer_unittests\": \"0f0015c718cf2d1dbbf9319d3f8aee2f937cce1e\", \"blink_heap_unittests\": \"b4226b2b59eaa279d40ca9541c5553f5b03b70bd\", \"blink_platform_unittests\": \"ecd1eab443704ad4ef7af84a0c2b78390922dec1\", \"blink_unittests\": \"1d76028161aad0a790a2281433c1552d1e355bc7\", \"boringssl_crypto_tests\": \"a770de1b0852ef96bc3c3e8a2651a0a7fbe5cf10\", \"boringssl_ssl_tests\": \"32796134b0fc74991c6488aa13c10ad22b840fb0\", \"browser_tests\": \"b5d03a54f7e3cdbb1e59bba7293aefb960904366\", \"capture_unittests\": \"e3ecbd9332fc0b7df5e8f3df898c27e55f4c0602\", \"cast_unittests\": \"613d88df906ddb6cf2fa53020249a97f852c7dc3\", \"cc_unittests\": \"559a20bd1c814a11ebeda5ec011f3073372084f7\", \"chrome_app_unittests\": \"54545957b55e801ad080ecf1242aed4f00eb24fd\", \"chromedriver_unittests\": \"8f99514168f7588f485ac6efa30947ed5968301d\", \"chromeos_components_unittests\": \"7d985f7858d344d579dcf073a653d046cc73679f\", \"chromeos_unittests\": \"db22acce9c09d405b6067923fef5098da005937c\", \"components_browsertests\": \"1e7d50bfe3e2b87d2d6cbfe54f97fe9832a02883\", \"components_unittests\": \"f17b35a631df925d5746f9c42f9c07a3cb1aeb0d\", \"compositor_unittests\": \"d3c6fce4e9a8af1fd33de4983ae4d0d07610dace\", \"content_browsertests\": \"5ff273b7cde9fe2ef5ed5a72ad8795bb12ca59b0\", \"content_unittests\": \"8a0310d0e709a91cfbed07638f928e73dfaf9ab7\", \"crashpad_tests\": \"3288f9e7c4d12b79d4b264d13e235ea98b8d1ad7\", \"crypto_unittests\": \"620a7cb3d5846fb649423475398bfa0222e9ca7e\", \"dbus_unittests\": \"12d33d8b862500ff335ec76b0f9d0844af643498\", \"device_unittests\": \"baf5ea165e55592511b6466e6bfa0a741b9c76a2\", \"display_unittests\": \"0f79a4a7c8e4ef9d39c046f882a3fd10f9e9a23a\", \"events_unittests\": \"9ed082b5956814c38f4d276de774c82b68f20b2e\", \"exo_unittests\": \"242d5a24835d07f3c93636876a491c4f0965bc7f\", \"extensions_browsertests\": \"dd0324bbf712b297a4cae483a7027c61dd5a6ad4\", \"extensions_unittests\": \"6d00b45ef5487803ba4ce1d5fc63e9bfd7094444\", \"filesystem_service_unittests\": \"4008b69b0f132892f7b50935171c5e4f4943b18d\", \"gcm_unit_tests\": \"6fc9e6d0e09e7f301097ee0b5c39e904929177b4\", \"gfx_unittests\": \"c52b95ffd4c3695ee423122d45f38ac0dd7338b3\", \"gin_unittests\": \"36518327181cb763937182d43bb340354ee3be0e\", \"gl_unittests_ozone\": \"381740a48adf2a547f94f36ee9d2a3ac31a4bcbf\", \"google_apis_unittests\": \"3b4c6534f3850e2fb58a9f891028d4ca0256f66a\", \"gpu_unittests\": \"a6a017084df72c010cc76f0dd7344cd984930d70\", \"gwp_asan_unittests\": \"7f98e1a9ce57ea562b04b9d658e0979d4fc2a840\", \"interactive_ui_tests\": \"0363db06ca5b22e469b9c15d2f5ead54fbba7c27\", \"ipc_tests\": \"7aa58f395372208b9fa2c8e6b163078893079443\", \"jingle_unittests\": \"7f5e6aa35c097836ed5312dad622a385a1286d11\", \"keyboard_unittests\": \"532326bfb371dc088256300fb4de5413610fe983\", \"latency_unittests\": \"2311c73b9cef716c74e5514cfc0f90188deedc29\", \"libjingle_xmpp_unittests\": \"e308239816b1904ca281eecb112091023296711c\", \"liburlpattern_unittests\": \"1398c8da3763fd826809bbe00aac164578012248\", \"media_blink_unittests\": \"eb48da77dd7322bebf3e1a9caa3051bbf70d1f28\", \"media_unittests\": \"69abbac24c371caa3141ae98fa7c495efed9eda8\", \"message_center_unittests\": \"594cfa4ba936399a9c453003a1952319cc8da1b7\", \"midi_unittests\": \"aaa61a3479ecd1233990e2d668e9ae6b31607b36\", \"mojo_core_unittests\": \"19cf4896fc010b36b488aef272f96a2fb9d1ca5b\", \"mojo_unittests\": \"1b8c23f93f34f240cc5ec0f85dca085ce93801f7\", \"nacl_helper_nonsfi_unittests\": \"3ff9c4fe7a7762206b5b36c4ef531adfd031245b\", \"nacl_loader_unittests\": \"a3f7d231c22ec19cac93b9909026495675ff7013\", \"native_theme_unittests\": \"85b63289fd94bab87a5c665244cc1aa4f64272fa\", \"net_unittests\": \"76d360ec4547123c3379d220cbbd2a4aba35e7d7\", \"ozone_gl_unittests\": \"b457caa3771ca41d5df72673d63792019162d034\", \"ozone_unittests\": \"9d890eb36b6acc4573c10f513582671f5b95977d\", \"ozone_x11_unittests\": \"3f5065ae2a53b09c4c265f8a50be284e55c349fe\", \"pdf_unittests\": \"8f82ffafd90d55c28b325e2fdf4308cdc50a9d07\", \"perfetto_unittests\": \"775dda73ff52cf65a37fe6794798117ee12c7882\", \"ppapi_unittests\": \"c002d80f9dfa945f3e958e871eae937155b2bec3\", \"printing_unittests\": \"590b74a602449e84b59ab8ace96c52cc969459d9\", \"remoting_unittests\": \"25ca61eca9b56ab09731f7b2d3d93658e1154d38\", \"sandbox_linux_unittests\": \"993bdcb68db16eb95a53a143bd2c362687fbf029\", \"service_manager_unittests\": \"9ec4a32616a060ac89561a34b1e09361ac0f199d\", \"services_unittests\": \"c4cb8d9358b9f1e42be3f910da3e1436d27b289a\", \"shell_dialogs_unittests\": \"82d59837fec745a506d6bce1bfe2a239149376fc\", \"shell_encryption_unittests\": \"34b18c78c4f41a85e71e8b59a53b3ef754c803a9\", \"skia_unittests\": \"64c4b49253826c234db622e153d732107b4afa06\", \"snapshot_unittests\": \"1b1024b700fd33be813349e35225c3eb5bcad38f\", \"sql_unittests\": \"ad561168c23cc383484e3448995372167734fbd6\", \"storage_unittests\": \"52e5eb0aa2bd5fc0bd6a138b6fd975a32748845b\", \"sync_integration_tests\": \"8c988ae61802caca714a798638d497c36f1bf4c1\", \"traffic_annotation_auditor_unittests\": \"ce331ba3bd727c48fd801fd1dbc7a29608c6ffd5\", \"ui_base_unittests\": \"4ef865d7b9a45ca6abcf20c1eade508e5b1cae2c\", \"ui_chromeos_unittests\": \"22debfc1f3e126ac1817e02a54230d7d29961f69\", \"ui_touch_selection_unittests\": \"1b07922a919f4239ca379e80b8885667783834c1\", \"unit_tests\": \"9de16b474bbc5420156b30c97beddd9e8bb8e52b\", \"url_unittests\": \"467308fb05065b64c770bb8fffd4363f8122ed83\", \"usage_time_limit_unittests\": \"940bd78391ff0c05b2c30612d2689703f727045c\", \"views_unittests\": \"5c01e67bfee3a7b3fb798c46d9ef9c6b7111fb78\", \"viz_unittests\": \"f21c77772079093b8d099745cc22fa233ce0c455\", \"wayland_client_perftests\": \"ad4771b88bf51e76ca54c5424d0de231a86989f3\", \"wm_unittests\": \"7af025c8f6e1cb8c0dd72a9c56bca8f6a96966cc\", \"wtf_unittests\": \"7110c705abe9d6411a9bbad7f8b73f8cfd242eb7\", \"zlib_unittests\": \"9df5064a389c37f282b2ff316515553a43f6a55b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_67bed7503890836458cdb06475180566d2d2cc5e3d31ceae5acef503f97becf0_v2\", \"builder_7701d7ffc8ab5fe38a709c77d4f18478e80f1a1d01dd8053f239f119334d81d1_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_dcef017f754690f3e0b2809d85023c2a50e42950d4504e0de4071c06ccf1a2cc_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-150-xtob\"], \"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\": [\"5673-2413400\"], \"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- TestAsNormalAndGuestUser/SpokenFeedbackTest.ChromeVoxNextTabRecovery/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611924441418757",
"status": "COMPLETED",
"status_changed_ts": "1611924441954531",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44999",
"builder:linux-chromeos-rel",
"buildset:commit/git/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f906c6c1194a339d3ed420e2442a69b6c8ed98a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022198510891287856",
"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/8856768448957693200/+/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:51670057dc8b9910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611927944855443",
"url": "https://ci.chromium.org/b/8856768448957693200",
"utcnow_ts": "1611927945406308"
}
}
[13:45:45.416] Invocation finished in 58m40.563687486s with status FAILED