[14:44:52.496] New invocation is queued and will start shortly
[14:44:53.686] Starting the invocation (attempt 1)
[14:44:53.719] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:44:53.719] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:44:53.719] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9055349589653555504",
"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\":\"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\",\"title\":\"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\",\"url\":\"https://gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-arm64-linux",
"scheduler_invocation_id:9055349589653555504",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[14:44:56.892] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1580309093979690",
"id": "8889919527430065696",
"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\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"title\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"url\": \"https://gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580309096456014",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9055349589653555504",
"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/8889919527430065696/+/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": "1580309096456158",
"url": "https://ci.chromium.org/b/8889919527430065696",
"utcnow_ts": "1580309096859950"
}
}
[14:44:56.892] Task URL: https://ci.chromium.org/b/8889919527430065696
[14:44:56.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:2:0) after 1m0s
[14:45:37.095] Received PubSub notification, asking Buildbucket for the build status
[14:45:37.210] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:56.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:2:0)
[14:45:56.975] Timer tick, asking Buildbucket for the build status
[14:45:57.118] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:57.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:4:0) after 1m0s
[14:46:57.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:4:0)
[14:46:57.165] Timer tick, asking Buildbucket for the build status
[14:46:57.293] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:57.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:5:0) after 1m0s
[14:47:57.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:5:0)
[14:47:57.347] Timer tick, asking Buildbucket for the build status
[14:47:57.554] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:57.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:6:0) after 1m0s
[14:48:57.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:6:0)
[14:48:57.764] Timer tick, asking Buildbucket for the build status
[14:48:58.291] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:58.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:7:0) after 1m0s
[14:49:58.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:7:0)
[14:49:58.655] Timer tick, asking Buildbucket for the build status
[14:49:58.830] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:58.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:8:0) after 1m0s
[14:50:59.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:8:0)
[14:50:59.059] Timer tick, asking Buildbucket for the build status
[14:50:59.212] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:59.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:9:0) after 1m0s
[14:51:59.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:9:0)
[14:51:59.383] Timer tick, asking Buildbucket for the build status
[14:51:59.465] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:59.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:10:0) after 1m0s
[14:52:59.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:10:0)
[14:52:59.518] Timer tick, asking Buildbucket for the build status
[14:52:59.572] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:59.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:11:0) after 1m0s
[14:53:59.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:11:0)
[14:53:59.645] Timer tick, asking Buildbucket for the build status
[14:53:59.980] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:59.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:12:0) after 1m0s
[14:54:59.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:12:0)
[14:54:59.957] Timer tick, asking Buildbucket for the build status
[14:55:00.304] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:00.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:13:0) after 1m0s
[14:56:00.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:13:0)
[14:56:00.359] Timer tick, asking Buildbucket for the build status
[14:56:00.461] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:00.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:14:0) after 1m0s
[14:57:00.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:14:0)
[14:57:00.485] Timer tick, asking Buildbucket for the build status
[14:57:00.686] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:00.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:15:0) after 1m0s
[14:58:00.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:15:0)
[14:58:00.820] Timer tick, asking Buildbucket for the build status
[14:58:01.059] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:01.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:16:0) after 1m0s
[14:59:01.260] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:16:0)
[14:59:01.260] Timer tick, asking Buildbucket for the build status
[14:59:01.383] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:01.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:17:0) after 1m0s
[15:00:01.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:17:0)
[15:00:01.571] Timer tick, asking Buildbucket for the build status
[15:00:01.862] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:01.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:18:0) after 1m0s
[15:01:01.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:18:0)
[15:01:01.928] Timer tick, asking Buildbucket for the build status
[15:01:02.233] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:02.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:19:0) after 1m0s
[15:02:02.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:19:0)
[15:02:02.288] Timer tick, asking Buildbucket for the build status
[15:02:02.448] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:02.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:20:0) after 1m0s
[15:03:02.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:20:0)
[15:03:02.562] Timer tick, asking Buildbucket for the build status
[15:03:03.178] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:03.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:21:0) after 1m0s
[15:04:03.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:21:0)
[15:04:03.224] Timer tick, asking Buildbucket for the build status
[15:04:03.353] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:03.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:22:0) after 1m0s
[15:05:03.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:22:0)
[15:05:03.372] Timer tick, asking Buildbucket for the build status
[15:05:03.619] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:03.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:23:0) after 1m0s
[15:06:03.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:23:0)
[15:06:03.698] Timer tick, asking Buildbucket for the build status
[15:06:04.016] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:04.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:24:0) after 1m0s
[15:07:04.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:24:0)
[15:07:04.071] Timer tick, asking Buildbucket for the build status
[15:07:04.379] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:04.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:25:0) after 1m0s
[15:08:04.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:25:0)
[15:08:04.468] Timer tick, asking Buildbucket for the build status
[15:08:04.586] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:04.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:26:0) after 1m0s
[15:09:04.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:26:0)
[15:09:04.708] Timer tick, asking Buildbucket for the build status
[15:09:06.651] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:06.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:27:0) after 1m0s
[15:10:06.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:27:0)
[15:10:06.741] Timer tick, asking Buildbucket for the build status
[15:10:06.919] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:06.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:28:0) after 1m0s
[15:11:07.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:28:0)
[15:11:07.084] Timer tick, asking Buildbucket for the build status
[15:11:07.474] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:07.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:29:0) after 1m0s
[15:12:07.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:29:0)
[15:12:07.674] Timer tick, asking Buildbucket for the build status
[15:12:07.983] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:07.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:30:0) after 1m0s
[15:13:08.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:30:0)
[15:13:08.099] Timer tick, asking Buildbucket for the build status
[15:13:08.266] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:08.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:31:0) after 1m0s
[15:14:08.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:31:0)
[15:14:08.286] Timer tick, asking Buildbucket for the build status
[15:14:08.413] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:08.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:32:0) after 1m0s
[15:15:08.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:32:0)
[15:15:08.509] Timer tick, asking Buildbucket for the build status
[15:15:08.645] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:08.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:33:0) after 1m0s
[15:16:08.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:33:0)
[15:16:08.649] Timer tick, asking Buildbucket for the build status
[15:16:08.775] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:08.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:34:0) after 1m0s
[15:17:08.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:34:0)
[15:17:08.845] Timer tick, asking Buildbucket for the build status
[15:17:09.056] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:09.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:35:0) after 1m0s
[15:18:09.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:35:0)
[15:18:09.136] Timer tick, asking Buildbucket for the build status
[15:18:10.373] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:10.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:36:0) after 1m0s
[15:19:10.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:36:0)
[15:19:10.444] Timer tick, asking Buildbucket for the build status
[15:19:10.573] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:10.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:37:0) after 1m0s
[15:20:10.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:37:0)
[15:20:10.616] Timer tick, asking Buildbucket for the build status
[15:20:10.744] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:10.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:38:0) after 1m0s
[15:21:10.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:38:0)
[15:21:10.807] Timer tick, asking Buildbucket for the build status
[15:21:11.347] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:11.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:39:0) after 1m0s
[15:22:11.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:39:0)
[15:22:11.429] Timer tick, asking Buildbucket for the build status
[15:22:11.815] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:11.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:40:0) after 1m0s
[15:23:11.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:40:0)
[15:23:11.879] Timer tick, asking Buildbucket for the build status
[15:23:11.989] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:11.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:41:0) after 1m0s
[15:24:12.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:41:0)
[15:24:12.057] Timer tick, asking Buildbucket for the build status
[15:24:12.179] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:12.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:42:0) after 1m0s
[15:25:12.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:42:0)
[15:25:12.362] Timer tick, asking Buildbucket for the build status
[15:25:12.545] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:12.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:43:0) after 1m0s
[15:26:12.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:43:0)
[15:26:12.762] Timer tick, asking Buildbucket for the build status
[15:26:12.856] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:12.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:44:0) after 1m0s
[15:27:13.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:44:0)
[15:27:13.082] Timer tick, asking Buildbucket for the build status
[15:27:13.283] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:13.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:45:0) after 1m0s
[15:28:13.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:45:0)
[15:28:13.379] Timer tick, asking Buildbucket for the build status
[15:28:13.531] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:13.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:46:0) after 1m0s
[15:29:13.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:46:0)
[15:29:13.678] Timer tick, asking Buildbucket for the build status
[15:29:13.804] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:13.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:47:0) after 1m0s
[15:30:14.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:47:0)
[15:30:14.007] Timer tick, asking Buildbucket for the build status
[15:30:14.444] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:14.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:48:0) after 1m0s
[15:31:14.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:48:0)
[15:31:14.588] Timer tick, asking Buildbucket for the build status
[15:31:15.005] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:15.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:49:0) after 1m0s
[15:32:15.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:49:0)
[15:32:15.017] Timer tick, asking Buildbucket for the build status
[15:32:15.187] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:15.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:50:0) after 1m0s
[15:33:15.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:50:0)
[15:33:15.326] Timer tick, asking Buildbucket for the build status
[15:33:15.691] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:15.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:51:0) after 1m0s
[15:34:15.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:51:0)
[15:34:15.718] Timer tick, asking Buildbucket for the build status
[15:34:16.107] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:16.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:52:0) after 1m0s
[15:35:16.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:52:0)
[15:35:16.553] Timer tick, asking Buildbucket for the build status
[15:35:16.687] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:16.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:53:0) after 1m0s
[15:36:16.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:53:0)
[15:36:16.761] Timer tick, asking Buildbucket for the build status
[15:36:18.455] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:18.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:54:0) after 1m0s
[15:37:18.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:54:0)
[15:37:18.518] Timer tick, asking Buildbucket for the build status
[15:37:18.852] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:18.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:55:0) after 1m0s
[15:38:18.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:55:0)
[15:38:18.905] Timer tick, asking Buildbucket for the build status
[15:38:19.677] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:19.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:56:0) after 1m0s
[15:39:19.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:56:0)
[15:39:19.806] Timer tick, asking Buildbucket for the build status
[15:39:20.072] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:20.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:57:0) after 1m0s
[15:40:20.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:57:0)
[15:40:20.259] Timer tick, asking Buildbucket for the build status
[15:40:20.953] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:20.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:58:0) after 1m0s
[15:41:20.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:58:0)
[15:41:20.983] Timer tick, asking Buildbucket for the build status
[15:41:21.128] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:21.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:59:0) after 1m0s
[15:42:21.284] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:59:0)
[15:42:21.284] Timer tick, asking Buildbucket for the build status
[15:42:21.461] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:21.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:60:0) after 1m0s
[15:43:21.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:60:0)
[15:43:21.481] Timer tick, asking Buildbucket for the build status
[15:43:21.625] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:21.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:61:0) after 1m0s
[15:44:21.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:61:0)
[15:44:21.774] Timer tick, asking Buildbucket for the build status
[15:44:21.979] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:21.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:62:0) after 1m0s
[15:45:22.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:62:0)
[15:45:22.079] Timer tick, asking Buildbucket for the build status
[15:45:22.307] Build 8889919527430065696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:22.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9055349589653555504:63:0) after 1m0s
[15:45:52.737] Received PubSub notification, asking Buildbucket for the build status
[15:45:52.831] Build 8889919527430065696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:45:52.831] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1580312751988574",
"created_by": "project:fuchsia",
"created_ts": "1580309093979690",
"id": "8889919527430065696",
"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\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"title\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"url\": \"https://gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}}",
"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\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"title\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\", \"url\": \"https://gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}]}, \"$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\": \"Jior1oYGIp_212p2LUnGYjqi7jB39U4HsREjqRt9xmgC\", \"isolated\": \"6a3f38c6ccf29b806ce159f249788059a0565ae6\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-arm64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"a1f8dca201ee08f526342ca9cdf022a9ea92e1b3\"}, \"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": "1580309135832610",
"status": "COMPLETED",
"status_changed_ts": "1580312752309880",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/a1f8dca201ee08f526342ca9cdf022a9ea92e1b3",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9055349589653555504",
"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/8889919527430065696/+/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:4a0a94b476ca5910",
"user_agent:luci-scheduler"
],
"updated_ts": "1580312752317543",
"url": "https://ci.chromium.org/b/8889919527430065696",
"utcnow_ts": "1580312752824576"
}
}
[15:45:52.831] Invocation finished in 1h1m0.355640565s with status SUCCEEDED