[20:43:29.239] New invocation is queued and will start shortly
[20:43:30.424] Starting the invocation (attempt 1)
[20:43:30.496] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:43:30.496] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:43:30.496] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9052518521751110800",
"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\":\"11d93ca76c04f79e43b6e39ab8658b07c0475932\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@11d93ca76c04f79e43b6e39ab8658b07c0475932\",\"title\":\"11d93ca76c04f79e43b6e39ab8658b07c0475932\",\"url\":\"https://gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-mac",
"scheduler_invocation_id:9052518521751110800",
"scheduler_job_id:fuchsia/prod-gcc-x64-mac",
"user_agent:luci-scheduler",
"buildset:commit/git/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[20:43:32.619] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1583009011308956",
"id": "8887088458917251008",
"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\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"title\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"url\": \"https://gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583009012180410",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052518521751110800",
"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/8887088458917251008/+/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": "1583009012180551",
"url": "https://ci.chromium.org/b/8887088458917251008",
"utcnow_ts": "1583009012609962"
}
}
[20:43:32.619] Task URL: https://ci.chromium.org/b/8887088458917251008
[20:43:32.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:2:0) after 1m0s
[20:43:50.972] Received PubSub notification, asking Buildbucket for the build status
[20:43:51.048] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:32.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:2:0)
[20:44:32.710] Timer tick, asking Buildbucket for the build status
[20:44:32.799] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:32.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:4:0) after 1m0s
[20:45:32.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:4:0)
[20:45:32.830] Timer tick, asking Buildbucket for the build status
[20:45:32.939] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:32.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:5:0) after 1m0s
[20:46:32.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:5:0)
[20:46:32.981] Timer tick, asking Buildbucket for the build status
[20:46:33.087] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:33.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:6:0) after 1m0s
[20:47:33.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:6:0)
[20:47:33.107] Timer tick, asking Buildbucket for the build status
[20:47:33.281] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:33.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:7:0) after 1m0s
[20:48:33.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:7:0)
[20:48:33.300] Timer tick, asking Buildbucket for the build status
[20:48:33.471] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:33.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:8:0) after 1m0s
[20:49:33.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:8:0)
[20:49:33.496] Timer tick, asking Buildbucket for the build status
[20:49:33.580] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:33.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:9:0) after 1m0s
[20:50:33.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:9:0)
[20:50:33.626] Timer tick, asking Buildbucket for the build status
[20:50:33.879] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:33.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:10:0) after 1m0s
[20:51:33.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:10:0)
[20:51:33.947] Timer tick, asking Buildbucket for the build status
[20:51:34.080] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:11:0) after 1m0s
[20:52:34.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:11:0)
[20:52:34.119] Timer tick, asking Buildbucket for the build status
[20:52:34.262] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:34.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:12:0) after 1m0s
[20:53:34.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:12:0)
[20:53:34.337] Timer tick, asking Buildbucket for the build status
[20:53:35.460] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:35.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:13:0) after 1m0s
[20:54:35.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:13:0)
[20:54:35.528] Timer tick, asking Buildbucket for the build status
[20:54:35.698] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:35.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:14:0) after 1m0s
[20:55:35.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:14:0)
[20:55:35.869] Timer tick, asking Buildbucket for the build status
[20:55:36.001] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:36.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:15:0) after 1m0s
[20:56:36.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:15:0)
[20:56:36.022] Timer tick, asking Buildbucket for the build status
[20:56:36.248] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:36.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:16:0) after 1m0s
[20:57:36.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:16:0)
[20:57:36.378] Timer tick, asking Buildbucket for the build status
[20:57:36.474] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:36.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:17:0) after 1m0s
[20:58:36.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:17:0)
[20:58:36.500] Timer tick, asking Buildbucket for the build status
[20:58:36.647] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:36.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:18:0) after 1m0s
[20:59:36.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:18:0)
[20:59:36.902] Timer tick, asking Buildbucket for the build status
[20:59:36.984] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:36.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:19:0) after 1m0s
[21:00:37.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:19:0)
[21:00:37.176] Timer tick, asking Buildbucket for the build status
[21:00:37.308] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:37.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:20:0) after 1m0s
[21:01:37.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:20:0)
[21:01:37.662] Timer tick, asking Buildbucket for the build status
[21:01:37.804] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:37.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:21:0) after 1m0s
[21:02:37.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:21:0)
[21:02:37.927] Timer tick, asking Buildbucket for the build status
[21:02:38.023] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:38.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:22:0) after 1m0s
[21:03:38.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:22:0)
[21:03:38.212] Timer tick, asking Buildbucket for the build status
[21:03:38.389] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:38.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:23:0) after 1m0s
[21:04:38.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:23:0)
[21:04:38.447] Timer tick, asking Buildbucket for the build status
[21:04:38.669] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:38.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:24:0) after 1m0s
[21:05:38.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:24:0)
[21:05:38.905] Timer tick, asking Buildbucket for the build status
[21:05:39.036] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:39.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:25:0) after 1m0s
[21:06:39.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:25:0)
[21:06:39.397] Timer tick, asking Buildbucket for the build status
[21:06:39.681] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:39.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:26:0) after 1m0s
[21:07:39.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:26:0)
[21:07:39.854] Timer tick, asking Buildbucket for the build status
[21:07:39.940] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:39.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:27:0) after 1m0s
[21:08:40.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:27:0)
[21:08:40.228] Timer tick, asking Buildbucket for the build status
[21:08:40.380] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:40.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:28:0) after 1m0s
[21:09:40.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:28:0)
[21:09:40.552] Timer tick, asking Buildbucket for the build status
[21:09:40.638] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:40.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:29:0) after 1m0s
[21:10:40.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:29:0)
[21:10:40.899] Timer tick, asking Buildbucket for the build status
[21:10:41.277] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:41.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:30:0) after 1m0s
[21:11:41.440] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:30:0)
[21:11:41.440] Timer tick, asking Buildbucket for the build status
[21:11:41.557] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:41.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:31:0) after 1m0s
[21:12:41.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:31:0)
[21:12:41.613] Timer tick, asking Buildbucket for the build status
[21:12:41.787] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:41.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:32:0) after 1m0s
[21:13:41.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:32:0)
[21:13:41.880] Timer tick, asking Buildbucket for the build status
[21:13:42.025] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:42.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:33:0) after 1m0s
[21:14:42.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:33:0)
[21:14:42.824] Timer tick, asking Buildbucket for the build status
[21:14:43.208] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:43.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:34:0) after 1m0s
[21:15:43.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:34:0)
[21:15:43.327] Timer tick, asking Buildbucket for the build status
[21:15:43.562] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:43.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:35:0) after 1m0s
[21:16:43.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:35:0)
[21:16:43.735] Timer tick, asking Buildbucket for the build status
[21:16:43.890] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:43.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:36:0) after 1m0s
[21:17:44.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:36:0)
[21:17:44.064] Timer tick, asking Buildbucket for the build status
[21:17:44.233] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:44.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:37:0) after 1m0s
[21:18:44.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:37:0)
[21:18:44.546] Timer tick, asking Buildbucket for the build status
[21:18:44.775] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:44.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:38:0) after 1m0s
[21:19:44.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:38:0)
[21:19:44.893] Timer tick, asking Buildbucket for the build status
[21:19:45.001] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:45.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:39:0) after 1m0s
[21:20:45.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:39:0)
[21:20:45.216] Timer tick, asking Buildbucket for the build status
[21:20:45.319] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:45.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:40:0) after 1m0s
[21:21:45.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:40:0)
[21:21:45.348] Timer tick, asking Buildbucket for the build status
[21:21:45.606] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:45.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:41:0) after 1m0s
[21:22:45.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:41:0)
[21:22:45.745] Timer tick, asking Buildbucket for the build status
[21:22:45.851] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:45.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:42:0) after 1m0s
[21:23:46.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:42:0)
[21:23:46.149] Timer tick, asking Buildbucket for the build status
[21:23:46.407] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:46.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:43:0) after 1m0s
[21:24:46.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:43:0)
[21:24:46.497] Timer tick, asking Buildbucket for the build status
[21:24:46.631] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:46.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:44:0) after 1m0s
[21:25:49.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:44:0)
[21:25:49.026] Timer tick, asking Buildbucket for the build status
[21:25:49.174] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:49.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:45:0) after 1m0s
[21:26:49.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:45:0)
[21:26:49.462] Timer tick, asking Buildbucket for the build status
[21:26:49.574] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:49.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:46:0) after 1m0s
[21:27:49.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:46:0)
[21:27:49.904] Timer tick, asking Buildbucket for the build status
[21:27:49.998] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:49.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:47:0) after 1m0s
[21:28:50.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:47:0)
[21:28:50.201] Timer tick, asking Buildbucket for the build status
[21:28:50.365] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:50.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:48:0) after 1m0s
[21:29:50.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:48:0)
[21:29:50.400] Timer tick, asking Buildbucket for the build status
[21:29:50.643] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:50.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:49:0) after 1m0s
[21:30:50.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:49:0)
[21:30:50.769] Timer tick, asking Buildbucket for the build status
[21:30:50.892] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:50.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:50:0) after 1m0s
[21:31:50.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:50:0)
[21:31:50.965] Timer tick, asking Buildbucket for the build status
[21:31:51.102] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:51.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:51:0) after 1m0s
[21:32:51.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:51:0)
[21:32:51.126] Timer tick, asking Buildbucket for the build status
[21:32:51.258] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:51.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:52:0) after 1m0s
[21:33:51.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:52:0)
[21:33:51.337] Timer tick, asking Buildbucket for the build status
[21:33:51.498] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:51.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:53:0) after 1m0s
[21:34:51.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:53:0)
[21:34:51.753] Timer tick, asking Buildbucket for the build status
[21:34:52.004] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:52.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:54:0) after 1m0s
[21:35:52.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:54:0)
[21:35:52.118] Timer tick, asking Buildbucket for the build status
[21:35:52.236] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:52.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:55:0) after 1m0s
[21:36:52.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:55:0)
[21:36:52.330] Timer tick, asking Buildbucket for the build status
[21:36:52.450] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:52.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:56:0) after 1m0s
[21:37:52.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:56:0)
[21:37:52.497] Timer tick, asking Buildbucket for the build status
[21:37:52.737] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:52.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:57:0) after 1m0s
[21:38:52.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:57:0)
[21:38:52.756] Timer tick, asking Buildbucket for the build status
[21:38:52.891] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:52.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:58:0) after 1m0s
[21:39:52.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:58:0)
[21:39:52.917] Timer tick, asking Buildbucket for the build status
[21:39:53.026] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:53.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:59:0) after 1m0s
[21:40:53.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:59:0)
[21:40:53.061] Timer tick, asking Buildbucket for the build status
[21:40:53.265] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:53.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:60:0) after 1m0s
[21:41:53.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:60:0)
[21:41:53.390] Timer tick, asking Buildbucket for the build status
[21:41:53.510] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:53.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:61:0) after 1m0s
[21:42:53.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:61:0)
[21:42:53.538] Timer tick, asking Buildbucket for the build status
[21:42:53.635] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:53.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:62:0) after 1m0s
[21:43:53.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:62:0)
[21:43:53.660] Timer tick, asking Buildbucket for the build status
[21:43:53.754] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:53.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:63:0) after 1m0s
[21:44:53.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:63:0)
[21:44:53.777] Timer tick, asking Buildbucket for the build status
[21:44:53.965] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:53.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:64:0) after 1m0s
[21:45:54.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:64:0)
[21:45:54.005] Timer tick, asking Buildbucket for the build status
[21:45:54.102] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:54.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:65:0) after 1m0s
[21:46:54.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:65:0)
[21:46:54.135] Timer tick, asking Buildbucket for the build status
[21:46:54.288] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:54.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:66:0) after 1m0s
[21:47:54.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:66:0)
[21:47:54.358] Timer tick, asking Buildbucket for the build status
[21:47:54.452] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:54.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:67:0) after 1m0s
[21:48:54.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:67:0)
[21:48:54.575] Timer tick, asking Buildbucket for the build status
[21:48:54.705] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:68:0) after 1m0s
[21:49:54.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:68:0)
[21:49:54.730] Timer tick, asking Buildbucket for the build status
[21:49:54.917] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:69:0) after 1m0s
[21:50:54.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:69:0)
[21:50:54.945] Timer tick, asking Buildbucket for the build status
[21:50:56.465] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:56.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:70:0) after 1m0s
[21:51:56.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:70:0)
[21:51:56.637] Timer tick, asking Buildbucket for the build status
[21:51:57.062] Build 8887088458917251008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:57.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052518521751110800:71:0) after 1m0s
[21:52:36.157] Received PubSub notification, asking Buildbucket for the build status
[21:52:36.275] Build 8887088458917251008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:52:36.275] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1583013155261022",
"created_by": "project:fuchsia",
"created_ts": "1583009011308956",
"id": "8887088458917251008",
"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\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"title\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"url\": \"https://gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}}",
"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\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"title\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\", \"url\": \"https://gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build385-a9\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-mac\", \"git_revision\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932,7076a6602c0e63e6233645f9d113d7b2e373b252\", \"instance_id\": \"C1U99gT6eXgV7Wr8sWTNEgy89OBCFJ-ylB9xiwNWu44C\", \"isolated\": \"4761719cb4e11812d580fa8f4f4bb71b8d918d80\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/mac-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"11d93ca76c04f79e43b6e39ab8658b07c0475932\"}, \"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\": [\"build385-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\": [\"4840-d3c86f9\"], \"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": "1583009029887734",
"status": "COMPLETED",
"status_changed_ts": "1583013155550286",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/11d93ca76c04f79e43b6e39ab8658b07c0475932",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052518521751110800",
"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/8887088458917251008/+/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:4aab82233fa73e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583013155558169",
"url": "https://ci.chromium.org/b/8887088458917251008",
"utcnow_ts": "1583013156267362"
}
}
[21:52:36.275] Invocation finished in 1h9m7.052558567s with status SUCCEEDED