[19:40:58.790] New invocation is queued and will start shortly
[19:40:59.947] Starting the invocation (attempt 1)
[19:41:00.013] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:41:00.013] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:41:00.013] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9052703648316211568",
"parameters_json": "{\"builder_name\":\"gcc-x64-mac\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/releases/gcc-9\",\"repo\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"98529e6d7408e896cb74c327ae60b3ccea055d9c\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@98529e6d7408e896cb74c327ae60b3ccea055d9c\",\"title\":\"98529e6d7408e896cb74c327ae60b3ccea055d9c\",\"url\":\"https://gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-mac",
"scheduler_invocation_id:9052703648316211568",
"scheduler_job_id:fuchsia/prod-gcc-x64-mac",
"user_agent:luci-scheduler",
"buildset:commit/git/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[19:41:00.774] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582832460170121",
"id": "8887273586203108224",
"parameters_json": "{\"builder_name\": \"gcc-x64-mac\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"title\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"url\": \"https://gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582832460487274",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052703648316211568",
"scheduler_job_id:fuchsia/prod-gcc-x64-mac",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887273586203108224/+/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": "1582832460487407",
"url": "https://ci.chromium.org/b/8887273586203108224",
"utcnow_ts": "1582832460766094"
}
}
[19:41:00.774] Task URL: https://ci.chromium.org/b/8887273586203108224
[19:41:00.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:2:0) after 1m0s
[19:41:05.385] Received PubSub notification, asking Buildbucket for the build status
[19:41:05.773] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:00.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:2:0)
[19:42:00.854] Timer tick, asking Buildbucket for the build status
[19:42:01.014] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:01.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:4:0) after 1m0s
[19:43:01.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:4:0)
[19:43:01.121] Timer tick, asking Buildbucket for the build status
[19:43:01.271] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:01.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:5:0) after 1m0s
[19:44:01.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:5:0)
[19:44:01.325] Timer tick, asking Buildbucket for the build status
[19:44:01.607] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:01.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:6:0) after 1m0s
[19:45:01.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:6:0)
[19:45:01.779] Timer tick, asking Buildbucket for the build status
[19:45:01.960] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:01.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:7:0) after 1m0s
[19:46:01.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:7:0)
[19:46:01.955] Timer tick, asking Buildbucket for the build status
[19:46:02.128] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:02.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:8:0) after 1m0s
[19:47:02.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:8:0)
[19:47:02.207] Timer tick, asking Buildbucket for the build status
[19:47:02.565] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:02.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:9:0) after 1m0s
[19:48:02.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:9:0)
[19:48:02.667] Timer tick, asking Buildbucket for the build status
[19:48:02.977] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:02.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:10:0) after 1m0s
[19:49:03.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:10:0)
[19:49:03.062] Timer tick, asking Buildbucket for the build status
[19:49:03.194] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:03.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:11:0) after 1m0s
[19:50:03.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:11:0)
[19:50:03.276] Timer tick, asking Buildbucket for the build status
[19:50:03.407] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:03.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:12:0) after 1m0s
[19:51:03.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:12:0)
[19:51:03.497] Timer tick, asking Buildbucket for the build status
[19:51:03.616] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:03.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:13:0) after 1m0s
[19:52:03.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:13:0)
[19:52:03.664] Timer tick, asking Buildbucket for the build status
[19:52:03.955] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:03.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:14:0) after 1m0s
[19:53:03.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:14:0)
[19:53:03.991] Timer tick, asking Buildbucket for the build status
[19:53:04.384] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:04.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:15:0) after 1m0s
[19:54:04.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:15:0)
[19:54:04.441] Timer tick, asking Buildbucket for the build status
[19:54:04.553] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:04.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:16:0) after 1m0s
[19:55:04.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:16:0)
[19:55:04.598] Timer tick, asking Buildbucket for the build status
[19:55:04.736] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:04.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:17:0) after 1m0s
[19:56:04.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:17:0)
[19:56:04.756] Timer tick, asking Buildbucket for the build status
[19:56:04.904] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:04.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:18:0) after 1m0s
[19:57:05.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:18:0)
[19:57:05.006] Timer tick, asking Buildbucket for the build status
[19:57:05.111] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:05.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:19:0) after 1m0s
[19:58:05.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:19:0)
[19:58:05.385] Timer tick, asking Buildbucket for the build status
[19:58:05.474] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:05.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:20:0) after 1m0s
[19:59:05.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:20:0)
[19:59:05.789] Timer tick, asking Buildbucket for the build status
[19:59:05.921] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:05.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:21:0) after 1m0s
[20:00:06.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:21:0)
[20:00:06.060] Timer tick, asking Buildbucket for the build status
[20:00:06.258] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:06.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:22:0) after 1m0s
[20:01:06.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:22:0)
[20:01:06.665] Timer tick, asking Buildbucket for the build status
[20:01:06.801] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:06.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:23:0) after 1m0s
[20:02:06.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:23:0)
[20:02:06.851] Timer tick, asking Buildbucket for the build status
[20:02:07.161] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:07.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:24:0) after 1m0s
[20:03:07.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:24:0)
[20:03:07.198] Timer tick, asking Buildbucket for the build status
[20:03:07.530] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:07.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:25:0) after 1m0s
[20:04:07.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:25:0)
[20:04:07.692] Timer tick, asking Buildbucket for the build status
[20:04:07.849] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:07.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:26:0) after 1m0s
[20:05:08.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:26:0)
[20:05:08.042] Timer tick, asking Buildbucket for the build status
[20:05:08.235] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:08.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:27:0) after 1m0s
[20:06:08.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:27:0)
[20:06:08.391] Timer tick, asking Buildbucket for the build status
[20:06:08.687] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:08.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:28:0) after 1m0s
[20:07:08.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:28:0)
[20:07:08.769] Timer tick, asking Buildbucket for the build status
[20:07:09.089] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:09.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:29:0) after 1m0s
[20:08:09.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:29:0)
[20:08:09.152] Timer tick, asking Buildbucket for the build status
[20:08:12.105] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:12.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:30:0) after 1m0s
[20:09:12.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:30:0)
[20:09:12.296] Timer tick, asking Buildbucket for the build status
[20:09:12.382] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:12.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:31:0) after 1m0s
[20:10:12.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:31:0)
[20:10:12.689] Timer tick, asking Buildbucket for the build status
[20:10:13.015] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:13.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:32:0) after 1m0s
[20:11:13.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:32:0)
[20:11:13.075] Timer tick, asking Buildbucket for the build status
[20:11:13.864] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:13.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:33:0) after 1m0s
[20:12:14.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:33:0)
[20:12:14.111] Timer tick, asking Buildbucket for the build status
[20:12:14.283] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:14.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:34:0) after 1m0s
[20:13:14.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:34:0)
[20:13:14.489] Timer tick, asking Buildbucket for the build status
[20:13:14.626] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:14.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:35:0) after 1m0s
[20:14:14.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:35:0)
[20:14:14.840] Timer tick, asking Buildbucket for the build status
[20:14:14.970] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:14.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:36:0) after 1m0s
[20:15:15.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:36:0)
[20:15:15.048] Timer tick, asking Buildbucket for the build status
[20:15:15.393] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:15.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:37:0) after 1m0s
[20:16:15.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:37:0)
[20:16:15.576] Timer tick, asking Buildbucket for the build status
[20:16:15.853] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:15.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:38:0) after 1m0s
[20:17:16.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:38:0)
[20:17:16.109] Timer tick, asking Buildbucket for the build status
[20:17:16.380] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:16.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:39:0) after 1m0s
[20:18:16.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:39:0)
[20:18:16.660] Timer tick, asking Buildbucket for the build status
[20:18:16.816] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:16.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:40:0) after 1m0s
[20:19:17.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:40:0)
[20:19:17.088] Timer tick, asking Buildbucket for the build status
[20:19:17.472] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:17.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:41:0) after 1m0s
[20:20:17.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:41:0)
[20:20:17.703] Timer tick, asking Buildbucket for the build status
[20:20:17.911] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:17.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:42:0) after 1m0s
[20:21:17.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:42:0)
[20:21:17.978] Timer tick, asking Buildbucket for the build status
[20:21:18.098] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:18.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:43:0) after 1m0s
[20:22:18.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:43:0)
[20:22:18.139] Timer tick, asking Buildbucket for the build status
[20:22:18.341] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:18.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:44:0) after 1m0s
[20:23:18.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:44:0)
[20:23:18.463] Timer tick, asking Buildbucket for the build status
[20:23:18.589] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:18.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:45:0) after 1m0s
[20:24:18.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:45:0)
[20:24:18.802] Timer tick, asking Buildbucket for the build status
[20:24:19.025] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:19.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:46:0) after 1m0s
[20:25:19.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:46:0)
[20:25:19.202] Timer tick, asking Buildbucket for the build status
[20:25:19.316] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:19.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:47:0) after 1m0s
[20:26:19.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:47:0)
[20:26:19.448] Timer tick, asking Buildbucket for the build status
[20:26:19.555] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:19.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:48:0) after 1m0s
[20:27:19.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:48:0)
[20:27:19.703] Timer tick, asking Buildbucket for the build status
[20:27:19.833] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:19.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:49:0) after 1m0s
[20:28:19.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:49:0)
[20:28:19.910] Timer tick, asking Buildbucket for the build status
[20:28:20.084] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:20.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:50:0) after 1m0s
[20:29:20.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:50:0)
[20:29:20.122] Timer tick, asking Buildbucket for the build status
[20:29:20.689] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:20.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:51:0) after 1m0s
[20:30:20.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:51:0)
[20:30:20.852] Timer tick, asking Buildbucket for the build status
[20:30:20.969] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:20.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:52:0) after 1m0s
[20:31:21.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:52:0)
[20:31:21.001] Timer tick, asking Buildbucket for the build status
[20:31:21.131] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:21.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:53:0) after 1m0s
[20:32:21.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:53:0)
[20:32:21.157] Timer tick, asking Buildbucket for the build status
[20:32:21.283] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:21.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:54:0) after 1m0s
[20:33:21.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:54:0)
[20:33:21.266] Timer tick, asking Buildbucket for the build status
[20:33:21.456] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:21.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:55:0) after 1m0s
[20:34:21.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:55:0)
[20:34:21.486] Timer tick, asking Buildbucket for the build status
[20:34:21.682] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:21.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:56:0) after 1m0s
[20:35:21.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:56:0)
[20:35:21.728] Timer tick, asking Buildbucket for the build status
[20:35:21.878] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:21.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:57:0) after 1m0s
[20:36:21.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:57:0)
[20:36:21.905] Timer tick, asking Buildbucket for the build status
[20:36:22.322] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:22.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:58:0) after 1m0s
[20:37:22.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:58:0)
[20:37:22.355] Timer tick, asking Buildbucket for the build status
[20:37:22.472] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:22.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:59:0) after 1m0s
[20:38:22.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:59:0)
[20:38:22.565] Timer tick, asking Buildbucket for the build status
[20:38:22.688] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:22.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:60:0) after 1m0s
[20:39:22.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:60:0)
[20:39:22.815] Timer tick, asking Buildbucket for the build status
[20:39:23.241] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:23.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:61:0) after 1m0s
[20:40:23.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:61:0)
[20:40:23.403] Timer tick, asking Buildbucket for the build status
[20:40:23.537] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:23.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:62:0) after 1m0s
[20:41:23.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:62:0)
[20:41:23.648] Timer tick, asking Buildbucket for the build status
[20:41:23.784] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:23.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:63:0) after 1m0s
[20:42:23.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:63:0)
[20:42:23.946] Timer tick, asking Buildbucket for the build status
[20:42:24.308] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:24.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:64:0) after 1m0s
[20:43:24.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:64:0)
[20:43:24.351] Timer tick, asking Buildbucket for the build status
[20:43:24.518] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:24.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:65:0) after 1m0s
[20:44:24.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:65:0)
[20:44:24.572] Timer tick, asking Buildbucket for the build status
[20:44:24.713] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:24.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:66:0) after 1m0s
[20:45:24.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:66:0)
[20:45:24.792] Timer tick, asking Buildbucket for the build status
[20:45:24.920] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:24.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:67:0) after 1m0s
[20:46:24.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:67:0)
[20:46:24.990] Timer tick, asking Buildbucket for the build status
[20:46:25.101] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:25.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:68:0) after 1m0s
[20:47:25.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:68:0)
[20:47:25.156] Timer tick, asking Buildbucket for the build status
[20:47:25.317] Build 8887273586203108224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:25.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052703648316211568:69:0) after 1m0s
[20:47:37.427] Received PubSub notification, asking Buildbucket for the build status
[20:47:37.759] Build 8887273586203108224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:47:37.759] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582836456742051",
"created_by": "project:fuchsia",
"created_ts": "1582832460170121",
"id": "8887273586203108224",
"parameters_json": "{\"builder_name\": \"gcc-x64-mac\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"title\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"url\": \"https://gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}}",
"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\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"title\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\", \"url\": \"https://gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build388-a9\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-mac\", \"git_revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c,3104d9ee223133b9f5be4ec96b3f986e4fbc893e\", \"instance_id\": \"DTFKZRF-CQasc9hl72DIyQkrt8MO8QZYz0Hz60Gl8gEC\", \"isolated\": \"32127712ac6b80e50fae567b1910686426fb3eba\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/mac-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"98529e6d7408e896cb74c327ae60b3ccea055d9c\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"24\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build388-a9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.10\"], \"role\": [\"builder\"], \"server_version\": [\"4838-b69615e\"], \"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": "1582832464494054",
"status": "COMPLETED",
"status_changed_ts": "1582836456959866",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/98529e6d7408e896cb74c327ae60b3ccea055d9c",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052703648316211568",
"scheduler_job_id:fuchsia/prod-gcc-x64-mac",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887273586203108224/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:gcc_toolchain",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4aa0fc3112664b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582836456969617",
"url": "https://ci.chromium.org/b/8887273586203108224",
"utcnow_ts": "1582836457738338"
}
}
[20:47:37.759] Invocation finished in 1h6m38.988360081s with status SUCCEEDED