[16:07:50.828] New invocation is queued and will start shortly
[16:07:52.056] Starting the invocation (attempt 1)
[16:07:52.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:07:52.099] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:07:52.099] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9055525563425378368",
"parameters_json": "{\"builder_name\":\"gcc-arm64-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/releases/gcc-9\",\"repo\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\",\"title\":\"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\",\"url\":\"https://gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-arm64-linux",
"scheduler_invocation_id:9055525563425378368",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[16:07:52.743] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1580141272183336",
"id": "8890095501337587840",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"title\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"url\": \"https://gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580141272484838",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9055525563425378368",
"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/8890095501337587840/+/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": "1580141272484984",
"url": "https://ci.chromium.org/b/8890095501337587840",
"utcnow_ts": "1580141272722851"
}
}
[16:07:52.743] Task URL: https://ci.chromium.org/b/8890095501337587840
[16:07:52.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:2:0) after 1m0s
[16:08:52.499] Received PubSub notification, asking Buildbucket for the build status
[16:08:52.679] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:52.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:2:0)
[16:08:52.886] Timer tick, asking Buildbucket for the build status
[16:08:52.987] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:52.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:4:0) after 1m0s
[16:09:53.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:4:0)
[16:09:53.009] Timer tick, asking Buildbucket for the build status
[16:09:53.215] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:53.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:5:0) after 1m0s
[16:10:53.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:5:0)
[16:10:53.236] Timer tick, asking Buildbucket for the build status
[16:10:53.392] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:53.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:6:0) after 1m0s
[16:11:53.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:6:0)
[16:11:53.453] Timer tick, asking Buildbucket for the build status
[16:11:53.656] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:53.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:7:0) after 1m0s
[16:12:53.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:7:0)
[16:12:53.648] Timer tick, asking Buildbucket for the build status
[16:12:53.779] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:53.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:8:0) after 1m0s
[16:13:53.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:8:0)
[16:13:53.823] Timer tick, asking Buildbucket for the build status
[16:13:53.933] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:53.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:9:0) after 1m0s
[16:14:53.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:9:0)
[16:14:53.949] Timer tick, asking Buildbucket for the build status
[16:14:54.160] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:54.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:10:0) after 1m0s
[16:15:54.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:10:0)
[16:15:54.185] Timer tick, asking Buildbucket for the build status
[16:15:54.309] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:54.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:11:0) after 1m0s
[16:16:54.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:11:0)
[16:16:54.388] Timer tick, asking Buildbucket for the build status
[16:16:54.485] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:54.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:12:0) after 1m0s
[16:17:54.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:12:0)
[16:17:54.558] Timer tick, asking Buildbucket for the build status
[16:17:54.850] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:54.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:13:0) after 1m0s
[16:18:54.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:13:0)
[16:18:54.953] Timer tick, asking Buildbucket for the build status
[16:18:55.062] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:55.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:14:0) after 1m0s
[16:19:55.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:14:0)
[16:19:55.148] Timer tick, asking Buildbucket for the build status
[16:19:55.379] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:55.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:15:0) after 1m0s
[16:20:55.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:15:0)
[16:20:55.406] Timer tick, asking Buildbucket for the build status
[16:20:55.554] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:55.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:16:0) after 1m0s
[16:21:55.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:16:0)
[16:21:55.601] Timer tick, asking Buildbucket for the build status
[16:21:55.782] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:55.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:17:0) after 1m0s
[16:22:55.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:17:0)
[16:22:55.921] Timer tick, asking Buildbucket for the build status
[16:22:56.210] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:56.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:18:0) after 1m0s
[16:23:56.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:18:0)
[16:23:56.213] Timer tick, asking Buildbucket for the build status
[16:23:56.306] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:56.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:19:0) after 1m0s
[16:24:56.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:19:0)
[16:24:56.343] Timer tick, asking Buildbucket for the build status
[16:24:56.599] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:56.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:20:0) after 1m0s
[16:25:56.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:20:0)
[16:25:56.603] Timer tick, asking Buildbucket for the build status
[16:25:56.716] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:56.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:21:0) after 1m0s
[16:26:56.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:21:0)
[16:26:56.713] Timer tick, asking Buildbucket for the build status
[16:26:56.914] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:56.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:22:0) after 1m0s
[16:27:56.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:22:0)
[16:27:56.947] Timer tick, asking Buildbucket for the build status
[16:27:57.064] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:57.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:23:0) after 1m0s
[16:28:57.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:23:0)
[16:28:57.161] Timer tick, asking Buildbucket for the build status
[16:28:57.401] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:57.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:24:0) after 1m0s
[16:29:57.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:24:0)
[16:29:57.422] Timer tick, asking Buildbucket for the build status
[16:29:57.522] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:57.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:25:0) after 1m0s
[16:30:57.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:25:0)
[16:30:57.621] Timer tick, asking Buildbucket for the build status
[16:30:57.817] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:57.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:26:0) after 1m0s
[16:31:57.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:26:0)
[16:31:57.849] Timer tick, asking Buildbucket for the build status
[16:31:57.996] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:57.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:27:0) after 1m0s
[16:32:58.040] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:27:0)
[16:32:58.040] Timer tick, asking Buildbucket for the build status
[16:32:58.533] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:58.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:28:0) after 1m0s
[16:33:58.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:28:0)
[16:33:58.573] Timer tick, asking Buildbucket for the build status
[16:33:58.678] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:58.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:29:0) after 1m0s
[16:34:58.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:29:0)
[16:34:58.751] Timer tick, asking Buildbucket for the build status
[16:34:59.064] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:59.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:30:0) after 1m0s
[16:35:59.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:30:0)
[16:35:59.087] Timer tick, asking Buildbucket for the build status
[16:35:59.481] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:59.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:31:0) after 1m0s
[16:36:59.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:31:0)
[16:36:59.501] Timer tick, asking Buildbucket for the build status
[16:36:59.630] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:59.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:32:0) after 1m0s
[16:37:59.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:32:0)
[16:37:59.664] Timer tick, asking Buildbucket for the build status
[16:37:59.946] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:59.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:33:0) after 1m0s
[16:38:59.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:33:0)
[16:38:59.967] Timer tick, asking Buildbucket for the build status
[16:39:00.143] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:00.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:34:0) after 1m0s
[16:40:00.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:34:0)
[16:40:00.154] Timer tick, asking Buildbucket for the build status
[16:40:00.356] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:00.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:35:0) after 1m0s
[16:41:00.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:35:0)
[16:41:00.485] Timer tick, asking Buildbucket for the build status
[16:41:01.084] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:01.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:36:0) after 1m0s
[16:42:01.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:36:0)
[16:42:01.138] Timer tick, asking Buildbucket for the build status
[16:42:01.449] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:01.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:37:0) after 1m0s
[16:43:01.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:37:0)
[16:43:01.459] Timer tick, asking Buildbucket for the build status
[16:43:01.603] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:01.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:38:0) after 1m0s
[16:44:01.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:38:0)
[16:44:01.623] Timer tick, asking Buildbucket for the build status
[16:44:01.906] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:01.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:39:0) after 1m0s
[16:45:01.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:39:0)
[16:45:01.978] Timer tick, asking Buildbucket for the build status
[16:45:02.045] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:02.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:40:0) after 1m0s
[16:46:02.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:40:0)
[16:46:02.070] Timer tick, asking Buildbucket for the build status
[16:46:02.180] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:02.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:41:0) after 1m0s
[16:47:02.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:41:0)
[16:47:02.188] Timer tick, asking Buildbucket for the build status
[16:47:02.899] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:02.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:42:0) after 1m0s
[16:48:02.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:42:0)
[16:48:02.917] Timer tick, asking Buildbucket for the build status
[16:48:03.198] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:03.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:43:0) after 1m0s
[16:49:03.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:43:0)
[16:49:03.226] Timer tick, asking Buildbucket for the build status
[16:49:03.449] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:03.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:44:0) after 1m0s
[16:50:03.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:44:0)
[16:50:03.528] Timer tick, asking Buildbucket for the build status
[16:50:03.625] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:03.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:45:0) after 1m0s
[16:51:03.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:45:0)
[16:51:03.640] Timer tick, asking Buildbucket for the build status
[16:51:03.765] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:03.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:46:0) after 1m0s
[16:52:03.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:46:0)
[16:52:03.811] Timer tick, asking Buildbucket for the build status
[16:52:03.912] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:03.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:47:0) after 1m0s
[16:53:04.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:47:0)
[16:53:04.005] Timer tick, asking Buildbucket for the build status
[16:53:04.224] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:04.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:48:0) after 1m0s
[16:54:04.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:48:0)
[16:54:04.296] Timer tick, asking Buildbucket for the build status
[16:54:04.591] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:04.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:49:0) after 1m0s
[16:55:04.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:49:0)
[16:55:04.688] Timer tick, asking Buildbucket for the build status
[16:55:04.783] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:04.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:50:0) after 1m0s
[16:56:04.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:50:0)
[16:56:04.845] Timer tick, asking Buildbucket for the build status
[16:56:05.414] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:05.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:51:0) after 1m0s
[16:57:05.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:51:0)
[16:57:05.533] Timer tick, asking Buildbucket for the build status
[16:57:05.828] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:05.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:52:0) after 1m0s
[16:58:05.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:52:0)
[16:58:05.849] Timer tick, asking Buildbucket for the build status
[16:58:06.004] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:06.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:53:0) after 1m0s
[16:59:06.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:53:0)
[16:59:06.101] Timer tick, asking Buildbucket for the build status
[16:59:06.509] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:06.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:54:0) after 1m0s
[17:00:06.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:54:0)
[17:00:06.550] Timer tick, asking Buildbucket for the build status
[17:00:06.786] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:06.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:55:0) after 1m0s
[17:01:06.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:55:0)
[17:01:06.803] Timer tick, asking Buildbucket for the build status
[17:01:06.926] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:06.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:56:0) after 1m0s
[17:02:06.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:56:0)
[17:02:06.944] Timer tick, asking Buildbucket for the build status
[17:02:07.114] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:07.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:57:0) after 1m0s
[17:03:07.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:57:0)
[17:03:07.100] Timer tick, asking Buildbucket for the build status
[17:03:07.251] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:07.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:58:0) after 1m0s
[17:04:07.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:58:0)
[17:04:07.318] Timer tick, asking Buildbucket for the build status
[17:04:07.582] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:07.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:59:0) after 1m0s
[17:05:07.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:59:0)
[17:05:07.596] Timer tick, asking Buildbucket for the build status
[17:05:07.727] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:07.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:60:0) after 1m0s
[17:06:07.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:60:0)
[17:06:07.837] Timer tick, asking Buildbucket for the build status
[17:06:08.083] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:08.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:61:0) after 1m0s
[17:07:08.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:61:0)
[17:07:08.104] Timer tick, asking Buildbucket for the build status
[17:07:08.590] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:08.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:62:0) after 1m0s
[17:08:08.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:62:0)
[17:08:08.601] Timer tick, asking Buildbucket for the build status
[17:08:08.723] Build 8890095501337587840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:08.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055525563425378368:63:0) after 1m0s
[17:08:50.439] Received PubSub notification, asking Buildbucket for the build status
[17:08:50.726] Build 8890095501337587840: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:08:50.726] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1580144929280054",
"created_by": "project:fuchsia",
"created_ts": "1580141272183336",
"id": "8890095501337587840",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"title\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"url\": \"https://gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}}",
"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/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"title\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\", \"url\": \"https://gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build414-a9\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-arm64-linux\", \"instance_id\": \"zTrU1rCrvU5yqE-V646UqrdDNtBnDgiJ6izhgVkTHAIC\", \"isolated\": \"9878be5f9b4adf59ecf188ce9ef3eaced7677d26\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-arm64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0\"}, \"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\": [\"4801-8ac0b59\"], \"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": "1580141331617442",
"status": "COMPLETED",
"status_changed_ts": "1580144929671574",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/2ceb5aba64e587f8ce66bec3c9af1f2cf60effa0",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9055525563425378368",
"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/8890095501337587840/+/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:4a0093e7bb542610",
"user_agent:luci-scheduler"
],
"updated_ts": "1580144929756600",
"url": "https://ci.chromium.org/b/8890095501337587840",
"utcnow_ts": "1580144930710615"
}
}
[17:08:50.726] Invocation finished in 1h0m59.917656252s with status SUCCEEDED