[06:33:36.043] New invocation is queued and will start shortly
[06:33:39.710] Starting the invocation (attempt 1)
[06:33:39.742] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:33:39.742] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:33:39.742] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9027567229264281456",
"parameters_json": "{\"builder_name\":\"gcc-prod-trigger\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5160d0f323d47031cb73c39a4dbd31d8976b4c8e\",\"title\":\"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@56af5a30b8efa34ce30f7d93da7dc981dda82f61\",\"title\":\"56af5a30b8efa34ce30f7d93da7dc981dda82f61\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9027567229264281456",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"gitiles_ref:refs/heads/master"
]
}
[06:33:40.267] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1606804419840226",
"id": "8862137164620374432",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5160d0f323d47031cb73c39a4dbd31d8976b4c8e\", \"title\": \"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@56af5a30b8efa34ce30f7d93da7dc981dda82f61\", \"title\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606804420156370",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027567229264281456",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8862137164620374432/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1606804420156486",
"url": "https://ci.chromium.org/b/8862137164620374432",
"utcnow_ts": "1606804420259958"
}
}
[06:33:40.267] Task URL: https://ci.chromium.org/b/8862137164620374432
[06:33:40.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:2:0) after 1m0s
[06:33:48.064] Received PubSub notification, asking Buildbucket for the build status
[06:33:48.429] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:40.243] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:2:0)
[06:34:40.243] Timer tick, asking Buildbucket for the build status
[06:34:40.361] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:40.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:4:0) after 1m0s
[06:35:40.401] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:4:0)
[06:35:40.401] Timer tick, asking Buildbucket for the build status
[06:35:40.493] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:5:0) after 1m0s
[06:36:40.510] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:5:0)
[06:36:40.510] Timer tick, asking Buildbucket for the build status
[06:36:40.646] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:6:0) after 1m0s
[06:37:40.718] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:6:0)
[06:37:40.718] Timer tick, asking Buildbucket for the build status
[06:37:40.795] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:40.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:7:0) after 1m0s
[06:38:40.813] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:7:0)
[06:38:40.813] Timer tick, asking Buildbucket for the build status
[06:38:40.907] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:40.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:8:0) after 1m0s
[06:39:40.949] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:8:0)
[06:39:40.949] Timer tick, asking Buildbucket for the build status
[06:39:41.037] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:41.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:9:0) after 1m0s
[06:40:41.073] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:9:0)
[06:40:41.073] Timer tick, asking Buildbucket for the build status
[06:40:42.884] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:42.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:10:0) after 1m0s
[06:41:42.975] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:10:0)
[06:41:42.975] Timer tick, asking Buildbucket for the build status
[06:41:43.132] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:43.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:11:0) after 1m0s
[06:42:43.154] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:11:0)
[06:42:43.154] Timer tick, asking Buildbucket for the build status
[06:42:43.279] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:43.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:12:0) after 1m0s
[06:43:43.296] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:12:0)
[06:43:43.296] Timer tick, asking Buildbucket for the build status
[06:43:43.417] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:43.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:13:0) after 1m0s
[06:44:43.469] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:13:0)
[06:44:43.469] Timer tick, asking Buildbucket for the build status
[06:44:43.574] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:43.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:14:0) after 1m0s
[06:45:43.563] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:14:0)
[06:45:43.563] Timer tick, asking Buildbucket for the build status
[06:45:43.666] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:43.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:15:0) after 1m0s
[06:46:43.715] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:15:0)
[06:46:43.715] Timer tick, asking Buildbucket for the build status
[06:46:43.782] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:43.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:16:0) after 1m0s
[06:47:43.835] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:16:0)
[06:47:43.835] Timer tick, asking Buildbucket for the build status
[06:47:44.042] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:44.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:17:0) after 1m0s
[06:48:44.112] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:17:0)
[06:48:44.112] Timer tick, asking Buildbucket for the build status
[06:48:44.231] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:44.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:18:0) after 1m0s
[06:49:44.253] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:18:0)
[06:49:44.253] Timer tick, asking Buildbucket for the build status
[06:49:44.373] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:44.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:19:0) after 1m0s
[06:50:44.394] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:19:0)
[06:50:44.394] Timer tick, asking Buildbucket for the build status
[06:50:44.487] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:20:0) after 1m0s
[06:51:44.537] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:20:0)
[06:51:44.537] Timer tick, asking Buildbucket for the build status
[06:51:44.752] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:44.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:21:0) after 1m0s
[06:52:44.778] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:21:0)
[06:52:44.779] Timer tick, asking Buildbucket for the build status
[06:52:45.004] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:45.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:22:0) after 1m0s
[06:53:45.016] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:22:0)
[06:53:45.016] Timer tick, asking Buildbucket for the build status
[06:53:45.355] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:45.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:23:0) after 1m0s
[06:54:45.372] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:23:0)
[06:54:45.372] Timer tick, asking Buildbucket for the build status
[06:54:45.459] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:45.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:24:0) after 1m0s
[06:55:45.513] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:24:0)
[06:55:45.513] Timer tick, asking Buildbucket for the build status
[06:55:45.677] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:45.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:25:0) after 1m0s
[06:56:45.682] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:25:0)
[06:56:45.682] Timer tick, asking Buildbucket for the build status
[06:56:45.768] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:45.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:26:0) after 1m0s
[06:57:45.827] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:26:0)
[06:57:45.827] Timer tick, asking Buildbucket for the build status
[06:57:45.914] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:45.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:27:0) after 1m0s
[06:58:45.965] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:27:0)
[06:58:45.965] Timer tick, asking Buildbucket for the build status
[06:58:46.073] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:46.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:28:0) after 1m0s
[06:59:46.124] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:28:0)
[06:59:46.124] Timer tick, asking Buildbucket for the build status
[06:59:46.705] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:46.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:29:0) after 1m0s
[07:00:47.003] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:29:0)
[07:00:47.003] Timer tick, asking Buildbucket for the build status
[07:00:47.068] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:47.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:30:0) after 1m0s
[07:01:47.330] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:30:0)
[07:01:47.330] Timer tick, asking Buildbucket for the build status
[07:01:47.402] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:47.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:31:0) after 1m0s
[07:02:47.449] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:31:0)
[07:02:47.449] Timer tick, asking Buildbucket for the build status
[07:02:47.523] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:47.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:32:0) after 1m0s
[07:03:47.897] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:32:0)
[07:03:47.897] Timer tick, asking Buildbucket for the build status
[07:03:48.002] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:48.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:33:0) after 1m0s
[07:04:48.169] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:33:0)
[07:04:48.169] Timer tick, asking Buildbucket for the build status
[07:04:48.252] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:48.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:34:0) after 1m0s
[07:05:48.514] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:34:0)
[07:05:48.514] Timer tick, asking Buildbucket for the build status
[07:05:48.576] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:48.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:35:0) after 1m0s
[07:06:49.068] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:35:0)
[07:06:49.068] Timer tick, asking Buildbucket for the build status
[07:06:49.127] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:49.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:36:0) after 1m0s
[07:07:49.355] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:36:0)
[07:07:49.355] Timer tick, asking Buildbucket for the build status
[07:07:49.431] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:49.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:37:0) after 1m0s
[07:08:49.572] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:37:0)
[07:08:49.572] Timer tick, asking Buildbucket for the build status
[07:08:49.897] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:49.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:38:0) after 1m0s
[07:09:50.051] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:38:0)
[07:09:50.051] Timer tick, asking Buildbucket for the build status
[07:09:50.114] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:50.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:39:0) after 1m0s
[07:10:50.233] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:39:0)
[07:10:50.233] Timer tick, asking Buildbucket for the build status
[07:10:50.295] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:50.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:40:0) after 1m0s
[07:11:50.501] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:40:0)
[07:11:50.501] Timer tick, asking Buildbucket for the build status
[07:11:51.055] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:51.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:41:0) after 1m0s
[07:12:51.086] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:41:0)
[07:12:51.086] Timer tick, asking Buildbucket for the build status
[07:12:51.144] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:51.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:42:0) after 1m0s
[07:13:51.238] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:42:0)
[07:13:51.238] Timer tick, asking Buildbucket for the build status
[07:13:51.394] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:51.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:43:0) after 1m0s
[07:14:51.477] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:43:0)
[07:14:51.477] Timer tick, asking Buildbucket for the build status
[07:14:51.567] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:51.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:44:0) after 1m0s
[07:15:51.821] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:44:0)
[07:15:51.821] Timer tick, asking Buildbucket for the build status
[07:15:51.927] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:51.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:45:0) after 1m0s
[07:16:51.990] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:45:0)
[07:16:51.990] Timer tick, asking Buildbucket for the build status
[07:16:52.058] Build 8862137164620374432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:52.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9027567229264281456:46:0) after 1m0s
[07:17:14.009] Received PubSub notification, asking Buildbucket for the build status
[07:17:14.801] Build 8862137164620374432: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:17:14.801] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1606807033580469",
"created_by": "project:fuchsia",
"created_ts": "1606804419840226",
"failure_reason": "BUILD_FAILURE",
"id": "8862137164620374432",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5160d0f323d47031cb73c39a4dbd31d8976b4c8e\", \"title\": \"5160d0f323d47031cb73c39a4dbd31d8976b4c8e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5160d0f323d47031cb73c39a4dbd31d8976b4c8e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@56af5a30b8efa34ce30f7d93da7dc981dda82f61\", \"title\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"56af5a30b8efa34ce30f7d93da7dc981dda82f61\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('display builds.build(s) failed') (retcode: 1)\"}, \"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder\", \"git\", \"goma_v2\", \"symbol\", \"vpython\"], \"cores\": [\"2\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-e2-2-ssd0-us-central1-f-1-98u8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20201027-46d3aed\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-2\"], \"os\": [\"Debian\", \"Debian-9\", \"Debian-9.11\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"Step('display builds.build(s) failed') (retcode: 1)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1606804427528182",
"status": "COMPLETED",
"status_changed_ts": "1606804427861219",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/56af5a30b8efa34ce30f7d93da7dc981dda82f61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027567229264281456",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8862137164620374432/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:5035d33fb3352410",
"user_agent:luci-scheduler"
],
"updated_ts": "1606807033630228",
"url": "https://ci.chromium.org/b/8862137164620374432",
"utcnow_ts": "1606807034790620"
}
}
[07:17:14.801] Invocation finished in 43m38.774179938s with status FAILED