[18:33:48.711] New invocation is queued and will start shortly
[18:33:49.836] Starting the invocation (attempt 1)
[18:33:49.876] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:49.876] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:49.876] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9054429216522594256",
"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\":\"01840b7a09722330ff36038001ea72dd7525c67e\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@01840b7a09722330ff36038001ea72dd7525c67e\",\"title\":\"01840b7a09722330ff36038001ea72dd7525c67e\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"01840b7a09722330ff36038001ea72dd7525c67e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-arm64-linux",
"scheduler_invocation_id:9054429216522594256",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/01840b7a09722330ff36038001ea72dd7525c67e",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e",
"gitiles_ref:refs/heads/master"
]
}
[18:33:50.443] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1581186829958159",
"id": "8888999154547759808",
"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\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@01840b7a09722330ff36038001ea72dd7525c67e\", \"title\": \"01840b7a09722330ff36038001ea72dd7525c67e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581186830252690",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/01840b7a09722330ff36038001ea72dd7525c67e",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054429216522594256",
"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/8888999154547759808/+/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": "1581186830252794",
"url": "https://ci.chromium.org/b/8888999154547759808",
"utcnow_ts": "1581186830436420"
}
}
[18:33:50.443] Task URL: https://ci.chromium.org/b/8888999154547759808
[18:33:50.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:2:0) after 1m0s
[18:34:50.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:2:0)
[18:34:50.471] Timer tick, asking Buildbucket for the build status
[18:34:50.584] Build 8888999154547759808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:50.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:3:0) after 1m0s
[18:34:51.796] Received PubSub notification, asking Buildbucket for the build status
[18:34:51.871] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:50.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:3:0)
[18:35:50.604] Timer tick, asking Buildbucket for the build status
[18:35:51.886] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:51.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:5:0) after 1m0s
[18:36:52.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:5:0)
[18:36:52.214] Timer tick, asking Buildbucket for the build status
[18:36:52.419] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:52.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:6:0) after 1m0s
[18:37:52.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:6:0)
[18:37:52.535] Timer tick, asking Buildbucket for the build status
[18:37:52.614] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:52.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:7:0) after 1m0s
[18:38:52.634] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:7:0)
[18:38:52.634] Timer tick, asking Buildbucket for the build status
[18:38:52.819] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:52.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:8:0) after 1m0s
[18:39:52.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:8:0)
[18:39:52.852] Timer tick, asking Buildbucket for the build status
[18:39:52.985] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:52.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:9:0) after 1m0s
[18:40:53.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:9:0)
[18:40:53.024] Timer tick, asking Buildbucket for the build status
[18:40:53.131] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:53.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:10:0) after 1m0s
[18:41:53.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:10:0)
[18:41:53.187] Timer tick, asking Buildbucket for the build status
[18:41:53.342] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:53.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:11:0) after 1m0s
[18:42:53.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:11:0)
[18:42:53.423] Timer tick, asking Buildbucket for the build status
[18:42:53.534] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:53.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:12:0) after 1m0s
[18:43:53.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:12:0)
[18:43:53.711] Timer tick, asking Buildbucket for the build status
[18:43:54.055] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:54.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:13:0) after 1m0s
[18:44:54.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:13:0)
[18:44:54.169] Timer tick, asking Buildbucket for the build status
[18:44:54.315] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:54.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:14:0) after 1m0s
[18:45:54.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:14:0)
[18:45:54.469] Timer tick, asking Buildbucket for the build status
[18:45:54.577] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:54.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:15:0) after 1m0s
[18:46:54.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:15:0)
[18:46:54.694] Timer tick, asking Buildbucket for the build status
[18:46:55.046] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:55.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:16:0) after 1m0s
[18:47:55.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:16:0)
[18:47:55.087] Timer tick, asking Buildbucket for the build status
[18:47:55.220] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:55.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:17:0) after 1m0s
[18:48:55.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:17:0)
[18:48:55.253] Timer tick, asking Buildbucket for the build status
[18:48:55.350] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:55.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:18:0) after 1m0s
[18:49:55.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:18:0)
[18:49:55.472] Timer tick, asking Buildbucket for the build status
[18:49:55.647] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:55.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:19:0) after 1m0s
[18:50:55.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:19:0)
[18:50:55.779] Timer tick, asking Buildbucket for the build status
[18:50:55.875] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:55.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:20:0) after 1m0s
[18:51:55.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:20:0)
[18:51:55.990] Timer tick, asking Buildbucket for the build status
[18:51:56.083] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:56.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:21:0) after 1m0s
[18:52:56.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:21:0)
[18:52:56.211] Timer tick, asking Buildbucket for the build status
[18:52:56.322] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:56.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:22:0) after 1m0s
[18:53:56.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:22:0)
[18:53:56.447] Timer tick, asking Buildbucket for the build status
[18:53:56.714] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:56.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:23:0) after 1m0s
[18:54:56.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:23:0)
[18:54:56.789] Timer tick, asking Buildbucket for the build status
[18:54:57.223] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:57.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:24:0) after 1m0s
[18:55:57.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:24:0)
[18:55:57.342] Timer tick, asking Buildbucket for the build status
[18:55:57.500] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:57.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:25:0) after 1m0s
[18:56:57.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:25:0)
[18:56:57.525] Timer tick, asking Buildbucket for the build status
[18:56:57.785] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:57.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:26:0) after 1m0s
[18:57:57.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:26:0)
[18:57:57.809] Timer tick, asking Buildbucket for the build status
[18:57:57.863] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:57.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:27:0) after 1m0s
[18:58:57.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:27:0)
[18:58:57.889] Timer tick, asking Buildbucket for the build status
[18:58:58.110] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:58.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:28:0) after 1m0s
[18:59:58.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:28:0)
[18:59:58.135] Timer tick, asking Buildbucket for the build status
[18:59:58.298] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:58.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:29:0) after 1m0s
[19:00:58.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:29:0)
[19:00:58.325] Timer tick, asking Buildbucket for the build status
[19:00:58.499] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:58.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:30:0) after 1m0s
[19:01:58.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:30:0)
[19:01:58.523] Timer tick, asking Buildbucket for the build status
[19:01:58.750] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:58.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:31:0) after 1m0s
[19:02:58.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:31:0)
[19:02:58.805] Timer tick, asking Buildbucket for the build status
[19:02:59.055] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:59.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:32:0) after 1m0s
[19:03:59.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:32:0)
[19:03:59.084] Timer tick, asking Buildbucket for the build status
[19:03:59.595] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:59.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:33:0) after 1m0s
[19:04:59.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:33:0)
[19:04:59.672] Timer tick, asking Buildbucket for the build status
[19:04:59.808] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:59.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:34:0) after 1m0s
[19:05:59.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:34:0)
[19:05:59.886] Timer tick, asking Buildbucket for the build status
[19:06:00.349] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:00.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:35:0) after 1m0s
[19:07:00.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:35:0)
[19:07:00.499] Timer tick, asking Buildbucket for the build status
[19:07:00.602] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:00.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:36:0) after 1m0s
[19:08:00.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:36:0)
[19:08:00.731] Timer tick, asking Buildbucket for the build status
[19:08:00.818] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:00.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:37:0) after 1m0s
[19:09:00.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:37:0)
[19:09:00.980] Timer tick, asking Buildbucket for the build status
[19:09:01.232] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:01.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:38:0) after 1m0s
[19:10:01.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:38:0)
[19:10:01.263] Timer tick, asking Buildbucket for the build status
[19:10:01.452] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:01.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:39:0) after 1m0s
[19:11:01.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:39:0)
[19:11:01.655] Timer tick, asking Buildbucket for the build status
[19:11:02.008] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:02.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:40:0) after 1m0s
[19:12:01.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:40:0)
[19:12:01.989] Timer tick, asking Buildbucket for the build status
[19:12:02.246] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:02.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:41:0) after 1m0s
[19:13:02.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:41:0)
[19:13:02.312] Timer tick, asking Buildbucket for the build status
[19:13:02.409] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:02.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:42:0) after 1m0s
[19:14:02.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:42:0)
[19:14:02.499] Timer tick, asking Buildbucket for the build status
[19:14:02.644] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:02.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:43:0) after 1m0s
[19:15:02.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:43:0)
[19:15:02.795] Timer tick, asking Buildbucket for the build status
[19:15:02.887] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:02.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:44:0) after 1m0s
[19:16:03.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:44:0)
[19:16:03.026] Timer tick, asking Buildbucket for the build status
[19:16:03.118] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:03.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:45:0) after 1m0s
[19:17:03.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:45:0)
[19:17:03.169] Timer tick, asking Buildbucket for the build status
[19:17:03.323] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:03.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:46:0) after 1m0s
[19:18:03.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:46:0)
[19:18:03.382] Timer tick, asking Buildbucket for the build status
[19:18:03.573] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:03.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:47:0) after 1m0s
[19:19:03.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:47:0)
[19:19:03.711] Timer tick, asking Buildbucket for the build status
[19:19:03.805] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:03.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:48:0) after 1m0s
[19:20:03.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:48:0)
[19:20:03.841] Timer tick, asking Buildbucket for the build status
[19:20:04.125] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:04.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:49:0) after 1m0s
[19:21:04.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:49:0)
[19:21:04.163] Timer tick, asking Buildbucket for the build status
[19:21:04.278] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:04.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:50:0) after 1m0s
[19:22:04.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:50:0)
[19:22:04.311] Timer tick, asking Buildbucket for the build status
[19:22:04.421] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:04.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:51:0) after 1m0s
[19:23:04.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:51:0)
[19:23:04.446] Timer tick, asking Buildbucket for the build status
[19:23:04.755] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:04.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:52:0) after 1m0s
[19:24:04.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:52:0)
[19:24:04.783] Timer tick, asking Buildbucket for the build status
[19:24:05.172] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:05.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:53:0) after 1m0s
[19:25:05.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:53:0)
[19:25:05.211] Timer tick, asking Buildbucket for the build status
[19:25:05.324] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:05.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:54:0) after 1m0s
[19:26:05.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:54:0)
[19:26:05.399] Timer tick, asking Buildbucket for the build status
[19:26:05.964] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:05.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:55:0) after 1m0s
[19:27:06.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:55:0)
[19:27:06.034] Timer tick, asking Buildbucket for the build status
[19:27:06.598] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:06.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:56:0) after 1m0s
[19:28:06.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:56:0)
[19:28:06.630] Timer tick, asking Buildbucket for the build status
[19:28:07.152] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:07.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:57:0) after 1m0s
[19:29:07.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:57:0)
[19:29:07.204] Timer tick, asking Buildbucket for the build status
[19:29:07.562] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:07.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:58:0) after 1m0s
[19:30:07.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:58:0)
[19:30:07.587] Timer tick, asking Buildbucket for the build status
[19:30:07.893] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:07.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:59:0) after 1m0s
[19:31:07.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:59:0)
[19:31:07.898] Timer tick, asking Buildbucket for the build status
[19:31:08.093] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:08.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:60:0) after 1m0s
[19:32:08.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:60:0)
[19:32:08.159] Timer tick, asking Buildbucket for the build status
[19:32:08.398] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:08.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:61:0) after 1m0s
[19:33:08.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:61:0)
[19:33:08.420] Timer tick, asking Buildbucket for the build status
[19:33:08.549] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:08.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:62:0) after 1m0s
[19:34:08.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:62:0)
[19:34:08.578] Timer tick, asking Buildbucket for the build status
[19:34:08.703] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:08.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:63:0) after 1m0s
[19:35:08.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:63:0)
[19:35:08.758] Timer tick, asking Buildbucket for the build status
[19:35:08.886] Build 8888999154547759808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:08.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:64:0) after 1m0s
[19:36:08.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9054429216522594256:64:0)
[19:36:08.912] Timer tick, asking Buildbucket for the build status
[19:36:09.037] Build 8888999154547759808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:36:09.037] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1581190557813906",
"created_by": "project:fuchsia",
"created_ts": "1581186829958159",
"id": "8888999154547759808",
"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\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@01840b7a09722330ff36038001ea72dd7525c67e\", \"title\": \"01840b7a09722330ff36038001ea72dd7525c67e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}}",
"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\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@01840b7a09722330ff36038001ea72dd7525c67e\", \"title\": \"01840b7a09722330ff36038001ea72dd7525c67e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build414-a9\", \"branch\": \"refs/heads/master\", \"buildername\": \"gcc-arm64-linux\", \"git_revision\": \"3f764f459a9c77f2af58f6878293e2b4bd1def78,01840b7a09722330ff36038001ea72dd7525c67e\", \"instance_id\": \"zLIBgV3t41TOdp___kuF4Ivgijv-rS3fr091rqnN-tkC\", \"isolated\": \"c87f6e8cceba25c920c208b092cda29d1d0478e9\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-arm64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"01840b7a09722330ff36038001ea72dd7525c67e\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"vpython\"], \"cores\": [\"256\"], \"cpu\": [\"arm\", \"arm-64\", \"arm64\", \"arm64-64\"], \"gce\": [\"0\"], \"gpu\": [\"1a03\", \"1a03:2000\"], \"id\": [\"build414-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": "1581186890949048",
"status": "COMPLETED",
"status_changed_ts": "1581190558167895",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/01840b7a09722330ff36038001ea72dd7525c67e",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/01840b7a09722330ff36038001ea72dd7525c67e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054429216522594256",
"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/8888999154547759808/+/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:4a3ee5da29b4f310",
"user_agent:luci-scheduler"
],
"updated_ts": "1581190558179012",
"url": "https://ci.chromium.org/b/8888999154547759808",
"utcnow_ts": "1581190569027733"
}
}
[19:36:09.037] Invocation finished in 1h2m20.344620448s with status SUCCEEDED