[01:16:20.152] New invocation is queued and will start shortly
[01:16:21.266] Starting the invocation (attempt 1)
[01:16:21.328] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:16:21.328] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:16:21.328] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9054494488873775632",
"parameters_json": "{\"builder_name\":\"gcc-arm64-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"919adfe8409211c726c1d05b47ca59890ee648f1\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@919adfe8409211c726c1d05b47ca59890ee648f1\",\"title\":\"919adfe8409211c726c1d05b47ca59890ee648f1\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"919adfe8409211c726c1d05b47ca59890ee648f1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-arm64-linux",
"scheduler_invocation_id:9054494488873775632",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/919adfe8409211c726c1d05b47ca59890ee648f1",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1",
"gitiles_ref:refs/heads/master"
]
}
[01:16:22.128] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1581124581454903",
"id": "8889064426835244176",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@919adfe8409211c726c1d05b47ca59890ee648f1\", \"title\": \"919adfe8409211c726c1d05b47ca59890ee648f1\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581124581863866",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/919adfe8409211c726c1d05b47ca59890ee648f1",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054494488873775632",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8889064426835244176/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:gcc_toolchain",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1581124581863967",
"url": "https://ci.chromium.org/b/8889064426835244176",
"utcnow_ts": "1581124582120885"
}
}
[01:16:22.128] Task URL: https://ci.chromium.org/b/8889064426835244176
[01:16:22.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:2:0) after 1m0s
[01:17:12.088] Received PubSub notification, asking Buildbucket for the build status
[01:17:12.281] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:22.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:2:0)
[01:17:22.166] Timer tick, asking Buildbucket for the build status
[01:17:22.244] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:22.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:4:0) after 1m0s
[01:18:22.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:4:0)
[01:18:22.339] Timer tick, asking Buildbucket for the build status
[01:18:22.521] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:22.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:5:0) after 1m0s
[01:19:22.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:5:0)
[01:19:22.714] Timer tick, asking Buildbucket for the build status
[01:19:22.901] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:22.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:6:0) after 1m0s
[01:20:23.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:6:0)
[01:20:23.047] Timer tick, asking Buildbucket for the build status
[01:20:23.174] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:23.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:7:0) after 1m0s
[01:21:23.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:7:0)
[01:21:23.513] Timer tick, asking Buildbucket for the build status
[01:21:23.659] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:23.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:8:0) after 1m0s
[01:22:23.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:8:0)
[01:22:23.699] Timer tick, asking Buildbucket for the build status
[01:22:23.924] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:23.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:9:0) after 1m0s
[01:23:24.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:9:0)
[01:23:24.128] Timer tick, asking Buildbucket for the build status
[01:23:24.592] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:24.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:10:0) after 1m0s
[01:24:24.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:10:0)
[01:24:24.734] Timer tick, asking Buildbucket for the build status
[01:24:25.039] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:25.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:11:0) after 1m0s
[01:25:25.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:11:0)
[01:25:25.124] Timer tick, asking Buildbucket for the build status
[01:25:25.235] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:25.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:12:0) after 1m0s
[01:26:25.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:12:0)
[01:26:25.266] Timer tick, asking Buildbucket for the build status
[01:26:25.359] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:25.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:13:0) after 1m0s
[01:27:25.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:13:0)
[01:27:25.471] Timer tick, asking Buildbucket for the build status
[01:27:26.424] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:26.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:14:0) after 1m0s
[01:28:26.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:14:0)
[01:28:26.483] Timer tick, asking Buildbucket for the build status
[01:28:26.886] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:26.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:15:0) after 1m0s
[01:29:26.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:15:0)
[01:29:26.983] Timer tick, asking Buildbucket for the build status
[01:29:27.227] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:27.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:16:0) after 1m0s
[01:30:27.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:16:0)
[01:30:27.307] Timer tick, asking Buildbucket for the build status
[01:30:27.439] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:27.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:17:0) after 1m0s
[01:31:27.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:17:0)
[01:31:27.499] Timer tick, asking Buildbucket for the build status
[01:31:27.609] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:27.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:18:0) after 1m0s
[01:32:27.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:18:0)
[01:32:27.676] Timer tick, asking Buildbucket for the build status
[01:32:27.990] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:27.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:19:0) after 1m0s
[01:33:28.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:19:0)
[01:33:28.064] Timer tick, asking Buildbucket for the build status
[01:33:28.182] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:28.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:20:0) after 1m0s
[01:34:28.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:20:0)
[01:34:28.286] Timer tick, asking Buildbucket for the build status
[01:34:28.478] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:28.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:21:0) after 1m0s
[01:35:28.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:21:0)
[01:35:28.538] Timer tick, asking Buildbucket for the build status
[01:35:28.851] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:28.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:22:0) after 1m0s
[01:36:29.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:22:0)
[01:36:29.014] Timer tick, asking Buildbucket for the build status
[01:36:29.128] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:29.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:23:0) after 1m0s
[01:37:29.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:23:0)
[01:37:29.202] Timer tick, asking Buildbucket for the build status
[01:37:29.305] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:29.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:24:0) after 1m0s
[01:38:29.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:24:0)
[01:38:29.521] Timer tick, asking Buildbucket for the build status
[01:38:29.626] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:29.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:25:0) after 1m0s
[01:39:29.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:25:0)
[01:39:29.649] Timer tick, asking Buildbucket for the build status
[01:39:29.790] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:29.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:26:0) after 1m0s
[01:40:29.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:26:0)
[01:40:29.877] Timer tick, asking Buildbucket for the build status
[01:40:30.256] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:30.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:27:0) after 1m0s
[01:41:30.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:27:0)
[01:41:30.350] Timer tick, asking Buildbucket for the build status
[01:41:31.008] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:31.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:28:0) after 1m0s
[01:42:31.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:28:0)
[01:42:31.093] Timer tick, asking Buildbucket for the build status
[01:42:31.214] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:31.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:29:0) after 1m0s
[01:43:31.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:29:0)
[01:43:31.323] Timer tick, asking Buildbucket for the build status
[01:43:31.431] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:31.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:30:0) after 1m0s
[01:44:31.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:30:0)
[01:44:31.478] Timer tick, asking Buildbucket for the build status
[01:44:31.956] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:31.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:31:0) after 1m0s
[01:45:32.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:31:0)
[01:45:32.126] Timer tick, asking Buildbucket for the build status
[01:45:32.254] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:32.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:32:0) after 1m0s
[01:46:32.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:32:0)
[01:46:32.347] Timer tick, asking Buildbucket for the build status
[01:46:32.504] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:32.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:33:0) after 1m0s
[01:47:32.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:33:0)
[01:47:32.611] Timer tick, asking Buildbucket for the build status
[01:47:32.736] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:34:0) after 1m0s
[01:48:32.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:34:0)
[01:48:32.805] Timer tick, asking Buildbucket for the build status
[01:48:32.983] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:32.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:35:0) after 1m0s
[01:49:33.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:35:0)
[01:49:33.037] Timer tick, asking Buildbucket for the build status
[01:49:33.322] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:36:0) after 1m0s
[01:50:33.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:36:0)
[01:50:33.463] Timer tick, asking Buildbucket for the build status
[01:50:33.640] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:33.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:37:0) after 1m0s
[01:51:33.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:37:0)
[01:51:33.762] Timer tick, asking Buildbucket for the build status
[01:51:34.120] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:38:0) after 1m0s
[01:52:34.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:38:0)
[01:52:34.184] Timer tick, asking Buildbucket for the build status
[01:52:34.302] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:34.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:39:0) after 1m0s
[01:53:34.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:39:0)
[01:53:34.321] Timer tick, asking Buildbucket for the build status
[01:53:34.457] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:34.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:40:0) after 1m0s
[01:54:34.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:40:0)
[01:54:34.757] Timer tick, asking Buildbucket for the build status
[01:54:35.086] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:35.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:41:0) after 1m0s
[01:55:35.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:41:0)
[01:55:35.228] Timer tick, asking Buildbucket for the build status
[01:55:35.386] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:35.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:42:0) after 1m0s
[01:56:35.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:42:0)
[01:56:35.426] Timer tick, asking Buildbucket for the build status
[01:56:35.723] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:35.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:43:0) after 1m0s
[01:57:35.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:43:0)
[01:57:35.749] Timer tick, asking Buildbucket for the build status
[01:57:35.913] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:35.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:44:0) after 1m0s
[01:58:35.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:44:0)
[01:58:35.945] Timer tick, asking Buildbucket for the build status
[01:58:36.098] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:45:0) after 1m0s
[01:59:36.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:45:0)
[01:59:36.126] Timer tick, asking Buildbucket for the build status
[01:59:36.233] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:36.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:46:0) after 1m0s
[02:00:36.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:46:0)
[02:00:36.434] Timer tick, asking Buildbucket for the build status
[02:00:36.619] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:36.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:47:0) after 1m0s
[02:01:36.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:47:0)
[02:01:36.766] Timer tick, asking Buildbucket for the build status
[02:01:37.057] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:37.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:48:0) after 1m0s
[02:02:37.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:48:0)
[02:02:37.146] Timer tick, asking Buildbucket for the build status
[02:02:37.282] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:37.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:49:0) after 1m0s
[02:03:37.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:49:0)
[02:03:37.354] Timer tick, asking Buildbucket for the build status
[02:03:37.522] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:37.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:50:0) after 1m0s
[02:04:37.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:50:0)
[02:04:37.578] Timer tick, asking Buildbucket for the build status
[02:04:37.972] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:37.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:51:0) after 1m0s
[02:05:38.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:51:0)
[02:05:38.155] Timer tick, asking Buildbucket for the build status
[02:05:38.515] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:38.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:52:0) after 1m0s
[02:06:38.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:52:0)
[02:06:38.537] Timer tick, asking Buildbucket for the build status
[02:06:38.635] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:38.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:53:0) after 1m0s
[02:07:38.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:53:0)
[02:07:38.703] Timer tick, asking Buildbucket for the build status
[02:07:38.812] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:38.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:54:0) after 1m0s
[02:08:38.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:54:0)
[02:08:38.886] Timer tick, asking Buildbucket for the build status
[02:08:39.129] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:39.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:55:0) after 1m0s
[02:09:39.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:55:0)
[02:09:39.221] Timer tick, asking Buildbucket for the build status
[02:09:39.560] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:39.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:56:0) after 1m0s
[02:10:39.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:56:0)
[02:10:39.683] Timer tick, asking Buildbucket for the build status
[02:10:39.907] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:39.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:57:0) after 1m0s
[02:11:39.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:57:0)
[02:11:39.974] Timer tick, asking Buildbucket for the build status
[02:11:40.336] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:40.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:58:0) after 1m0s
[02:12:40.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:58:0)
[02:12:40.363] Timer tick, asking Buildbucket for the build status
[02:12:40.616] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:40.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:59:0) after 1m0s
[02:13:40.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:59:0)
[02:13:40.699] Timer tick, asking Buildbucket for the build status
[02:13:40.837] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:40.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:60:0) after 1m0s
[02:14:40.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:60:0)
[02:14:40.866] Timer tick, asking Buildbucket for the build status
[02:14:40.976] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:40.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:61:0) after 1m0s
[02:15:41.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:61:0)
[02:15:41.047] Timer tick, asking Buildbucket for the build status
[02:15:41.416] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:41.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:62:0) after 1m0s
[02:16:41.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:62:0)
[02:16:41.502] Timer tick, asking Buildbucket for the build status
[02:16:41.719] Build 8889064426835244176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:41.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054494488873775632:63:0) after 1m0s
[02:16:43.649] Received PubSub notification, asking Buildbucket for the build status
[02:16:43.858] Build 8889064426835244176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:16:43.858] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1581128202764000",
"created_by": "project:fuchsia",
"created_ts": "1581124581454903",
"id": "8889064426835244176",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@919adfe8409211c726c1d05b47ca59890ee648f1\", \"title\": \"919adfe8409211c726c1d05b47ca59890ee648f1\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@919adfe8409211c726c1d05b47ca59890ee648f1\", \"title\": \"919adfe8409211c726c1d05b47ca59890ee648f1\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build413-a9\", \"branch\": \"refs/heads/master\", \"buildername\": \"gcc-arm64-linux\", \"git_revision\": \"3f764f459a9c77f2af58f6878293e2b4bd1def78,919adfe8409211c726c1d05b47ca59890ee648f1\", \"instance_id\": \"tOHR3wholRV0-tqDZP9VAxne9RpHhFRjQcf_F1PWaikC\", \"isolated\": \"49960c3f057f28c6086e3080adc07e0e594ea1a9\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-arm64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"919adfe8409211c726c1d05b47ca59890ee648f1\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"vpython\"], \"cores\": [\"256\"], \"cpu\": [\"arm\", \"arm-64\", \"arm64\", \"arm64-64\"], \"gce\": [\"0\"], \"gpu\": [\"1a03\", \"1a03:2000\"], \"id\": [\"build413-a9\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-highcpu-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-18.04\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.15+\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-mtv\", \"us-mtv-chops\", \"us-mtv-chops-a9\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1581124630936419",
"status": "COMPLETED",
"status_changed_ts": "1581128203243506",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/919adfe8409211c726c1d05b47ca59890ee648f1",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/919adfe8409211c726c1d05b47ca59890ee648f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054494488873775632",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8889064426835244176/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:gcc_toolchain",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4a3b3003986db210",
"user_agent:luci-scheduler"
],
"updated_ts": "1581128203251835",
"url": "https://ci.chromium.org/b/8889064426835244176",
"utcnow_ts": "1581128203838245"
}
}
[02:16:43.858] Invocation finished in 1h0m23.730965699s with status SUCCEEDED