[19:42:25.206] New invocation is queued and will start shortly
[19:42:26.293] Starting the invocation (attempt 1)
[19:42:26.333] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:42:26.333] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:42:26.333] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9052612960754363712",
"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\":\"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@9179ae6a0df27e3d3830ee42f755dc7d76cb0423\",\"title\":\"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\",\"url\":\"https://gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-mac",
"scheduler_invocation_id:9052612960754363712",
"scheduler_job_id:fuchsia/prod-gcc-x64-mac",
"user_agent:luci-scheduler",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[19:42:27.243] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582918946579385",
"id": "8887182898630072560",
"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\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"title\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"url\": \"https://gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582918947090287",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052612960754363712",
"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/8887182898630072560/+/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": "1582918947090488",
"url": "https://ci.chromium.org/b/8887182898630072560",
"utcnow_ts": "1582918947227159"
}
}
[19:42:27.243] Task URL: https://ci.chromium.org/b/8887182898630072560
[19:42:27.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:2:0) after 1m0s
[19:42:30.210] Received PubSub notification, asking Buildbucket for the build status
[19:42:30.454] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:2:0)
[19:43:27.274] Timer tick, asking Buildbucket for the build status
[19:43:27.876] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:4:0) after 1m0s
[19:44:27.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:4:0)
[19:44:27.953] Timer tick, asking Buildbucket for the build status
[19:44:29.448] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:29.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:5:0) after 1m0s
[19:45:29.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:5:0)
[19:45:29.554] Timer tick, asking Buildbucket for the build status
[19:45:29.799] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:29.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:6:0) after 1m0s
[19:46:29.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:6:0)
[19:46:29.860] Timer tick, asking Buildbucket for the build status
[19:46:30.095] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:30.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:7:0) after 1m0s
[19:47:30.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:7:0)
[19:47:30.124] Timer tick, asking Buildbucket for the build status
[19:47:30.524] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:30.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:8:0) after 1m0s
[19:48:30.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:8:0)
[19:48:30.611] Timer tick, asking Buildbucket for the build status
[19:48:31.809] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:31.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:9:0) after 1m0s
[19:49:31.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:9:0)
[19:49:31.830] Timer tick, asking Buildbucket for the build status
[19:49:32.122] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:32.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:10:0) after 1m0s
[19:50:32.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:10:0)
[19:50:32.279] Timer tick, asking Buildbucket for the build status
[19:50:32.630] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:32.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:11:0) after 1m0s
[19:51:32.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:11:0)
[19:51:32.693] Timer tick, asking Buildbucket for the build status
[19:51:33.022] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:33.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:12:0) after 1m0s
[19:52:33.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:12:0)
[19:52:33.057] Timer tick, asking Buildbucket for the build status
[19:52:33.310] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:33.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:13:0) after 1m0s
[19:53:33.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:13:0)
[19:53:33.330] Timer tick, asking Buildbucket for the build status
[19:53:35.157] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:35.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:14:0) after 1m0s
[19:54:35.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:14:0)
[19:54:35.179] Timer tick, asking Buildbucket for the build status
[19:54:35.480] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:35.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:15:0) after 1m0s
[19:55:35.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:15:0)
[19:55:35.558] Timer tick, asking Buildbucket for the build status
[19:55:37.525] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:37.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:16:0) after 1m0s
[19:56:37.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:16:0)
[19:56:37.518] Timer tick, asking Buildbucket for the build status
[19:56:37.678] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:37.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:17:0) after 1m0s
[19:57:37.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:17:0)
[19:57:37.713] Timer tick, asking Buildbucket for the build status
[19:57:37.904] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:37.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:18:0) after 1m0s
[19:58:37.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:18:0)
[19:58:37.969] Timer tick, asking Buildbucket for the build status
[19:58:38.624] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:38.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:19:0) after 1m0s
[19:59:38.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:19:0)
[19:59:38.645] Timer tick, asking Buildbucket for the build status
[19:59:38.998] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:38.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:20:0) after 1m0s
[20:00:39.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:20:0)
[20:00:39.053] Timer tick, asking Buildbucket for the build status
[20:00:39.742] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:39.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:21:0) after 1m0s
[20:01:39.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:21:0)
[20:01:39.759] Timer tick, asking Buildbucket for the build status
[20:01:39.943] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:39.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:22:0) after 1m0s
[20:02:39.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:22:0)
[20:02:39.993] Timer tick, asking Buildbucket for the build status
[20:02:40.316] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:40.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:23:0) after 1m0s
[20:03:40.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:23:0)
[20:03:40.389] Timer tick, asking Buildbucket for the build status
[20:03:40.585] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:40.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:24:0) after 1m0s
[20:04:40.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:24:0)
[20:04:40.848] Timer tick, asking Buildbucket for the build status
[20:04:42.052] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:42.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:25:0) after 1m0s
[20:05:42.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:25:0)
[20:05:42.172] Timer tick, asking Buildbucket for the build status
[20:05:42.541] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:42.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:26:0) after 1m0s
[20:06:42.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:26:0)
[20:06:42.742] Timer tick, asking Buildbucket for the build status
[20:06:42.913] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:42.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:27:0) after 1m0s
[20:07:43.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:27:0)
[20:07:43.113] Timer tick, asking Buildbucket for the build status
[20:07:43.371] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:43.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:28:0) after 1m0s
[20:08:43.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:28:0)
[20:08:43.809] Timer tick, asking Buildbucket for the build status
[20:08:47.039] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:47.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:29:0) after 1m0s
[20:09:47.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:29:0)
[20:09:47.493] Timer tick, asking Buildbucket for the build status
[20:09:47.965] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:47.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:30:0) after 1m0s
[20:10:48.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:30:0)
[20:10:48.099] Timer tick, asking Buildbucket for the build status
[20:10:48.319] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:48.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:31:0) after 1m0s
[20:11:48.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:31:0)
[20:11:48.612] Timer tick, asking Buildbucket for the build status
[20:11:49.105] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:49.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:32:0) after 1m0s
[20:12:49.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:32:0)
[20:12:49.271] Timer tick, asking Buildbucket for the build status
[20:12:49.842] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:49.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:33:0) after 1m0s
[20:13:49.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:33:0)
[20:13:49.891] Timer tick, asking Buildbucket for the build status
[20:13:50.012] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:50.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:34:0) after 1m0s
[20:14:50.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:34:0)
[20:14:50.354] Timer tick, asking Buildbucket for the build status
[20:14:50.648] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:50.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:35:0) after 1m0s
[20:15:50.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:35:0)
[20:15:50.933] Timer tick, asking Buildbucket for the build status
[20:15:51.243] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:51.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:36:0) after 1m0s
[20:16:51.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:36:0)
[20:16:51.428] Timer tick, asking Buildbucket for the build status
[20:16:52.042] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:52.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:37:0) after 1m0s
[20:17:52.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:37:0)
[20:17:52.142] Timer tick, asking Buildbucket for the build status
[20:17:53.154] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:53.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:38:0) after 1m0s
[20:18:53.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:38:0)
[20:18:53.182] Timer tick, asking Buildbucket for the build status
[20:18:53.367] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:53.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:39:0) after 1m0s
[20:19:53.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:39:0)
[20:19:53.700] Timer tick, asking Buildbucket for the build status
[20:19:53.863] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:53.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:40:0) after 1m0s
[20:20:53.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:40:0)
[20:20:53.923] Timer tick, asking Buildbucket for the build status
[20:20:54.157] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:54.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:41:0) after 1m0s
[20:21:54.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:41:0)
[20:21:54.237] Timer tick, asking Buildbucket for the build status
[20:21:54.400] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:54.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:42:0) after 1m0s
[20:22:54.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:42:0)
[20:22:54.496] Timer tick, asking Buildbucket for the build status
[20:22:54.835] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:54.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:43:0) after 1m0s
[20:23:54.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:43:0)
[20:23:54.908] Timer tick, asking Buildbucket for the build status
[20:23:55.353] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:55.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:44:0) after 1m0s
[20:24:55.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:44:0)
[20:24:55.414] Timer tick, asking Buildbucket for the build status
[20:24:55.633] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:55.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:45:0) after 1m0s
[20:25:55.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:45:0)
[20:25:55.727] Timer tick, asking Buildbucket for the build status
[20:25:55.875] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:55.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:46:0) after 1m0s
[20:26:56.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:46:0)
[20:26:56.052] Timer tick, asking Buildbucket for the build status
[20:26:56.238] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:56.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:47:0) after 1m0s
[20:27:56.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:47:0)
[20:27:56.278] Timer tick, asking Buildbucket for the build status
[20:27:56.428] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:56.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:48:0) after 1m0s
[20:28:56.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:48:0)
[20:28:56.465] Timer tick, asking Buildbucket for the build status
[20:28:57.153] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:57.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:49:0) after 1m0s
[20:29:57.229] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:49:0)
[20:29:57.229] Timer tick, asking Buildbucket for the build status
[20:29:57.596] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:57.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:50:0) after 1m0s
[20:30:57.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:50:0)
[20:30:57.733] Timer tick, asking Buildbucket for the build status
[20:30:57.945] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:57.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:51:0) after 1m0s
[20:31:57.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:51:0)
[20:31:57.987] Timer tick, asking Buildbucket for the build status
[20:31:58.161] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:58.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:52:0) after 1m0s
[20:32:58.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:52:0)
[20:32:58.224] Timer tick, asking Buildbucket for the build status
[20:32:58.361] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:58.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:53:0) after 1m0s
[20:33:58.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:53:0)
[20:33:58.474] Timer tick, asking Buildbucket for the build status
[20:33:59.005] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:59.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:54:0) after 1m0s
[20:34:59.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:54:0)
[20:34:59.061] Timer tick, asking Buildbucket for the build status
[20:34:59.169] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:59.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:55:0) after 1m0s
[20:35:59.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:55:0)
[20:35:59.221] Timer tick, asking Buildbucket for the build status
[20:35:59.388] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:59.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:56:0) after 1m0s
[20:36:59.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:56:0)
[20:36:59.433] Timer tick, asking Buildbucket for the build status
[20:36:59.673] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:59.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:57:0) after 1m0s
[20:37:59.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:57:0)
[20:37:59.728] Timer tick, asking Buildbucket for the build status
[20:38:00.353] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:00.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:58:0) after 1m0s
[20:39:00.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:58:0)
[20:39:00.400] Timer tick, asking Buildbucket for the build status
[20:39:00.530] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:00.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:59:0) after 1m0s
[20:40:00.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:59:0)
[20:40:00.602] Timer tick, asking Buildbucket for the build status
[20:40:00.761] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:00.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:60:0) after 1m0s
[20:41:00.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:60:0)
[20:41:00.825] Timer tick, asking Buildbucket for the build status
[20:41:01.177] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:01.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:61:0) after 1m0s
[20:42:01.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:61:0)
[20:42:01.568] Timer tick, asking Buildbucket for the build status
[20:42:01.753] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:01.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:62:0) after 1m0s
[20:43:02.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:62:0)
[20:43:02.041] Timer tick, asking Buildbucket for the build status
[20:43:02.568] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:02.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:63:0) after 1m0s
[20:44:02.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:63:0)
[20:44:02.615] Timer tick, asking Buildbucket for the build status
[20:44:02.997] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:02.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:64:0) after 1m0s
[20:45:03.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:64:0)
[20:45:03.018] Timer tick, asking Buildbucket for the build status
[20:45:03.627] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:03.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:65:0) after 1m0s
[20:46:03.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:65:0)
[20:46:03.805] Timer tick, asking Buildbucket for the build status
[20:46:04.685] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:04.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:66:0) after 1m0s
[20:47:04.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:66:0)
[20:47:04.772] Timer tick, asking Buildbucket for the build status
[20:47:05.166] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:05.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:67:0) after 1m0s
[20:48:05.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:67:0)
[20:48:05.194] Timer tick, asking Buildbucket for the build status
[20:48:05.671] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:05.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:68:0) after 1m0s
[20:49:05.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:68:0)
[20:49:05.694] Timer tick, asking Buildbucket for the build status
[20:49:06.065] Build 8887182898630072560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:06.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-mac:9052612960754363712:69:0) after 1m0s
[20:49:34.044] Received PubSub notification, asking Buildbucket for the build status
[20:49:36.061] Build 8887182898630072560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:49:36.061] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582922972457443",
"created_by": "project:fuchsia",
"created_ts": "1582918946579385",
"id": "8887182898630072560",
"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\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"title\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"url\": \"https://gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}}",
"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\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"title\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\", \"url\": \"https://gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}]}, \"$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\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423,d7592e974706637058867b02626c52a30ef0a2ee\", \"instance_id\": \"mq2TITL19AGHXT_-mm8aursxI8oVmxFL28ipkiQe6qoC\", \"isolated\": \"c460d7b8175686c10650f1b0b88c9afc465fcc2c\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/mac-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}, \"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\": [\"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": "1582918949337539",
"status": "COMPLETED",
"status_changed_ts": "1582922973359662",
"tags": [
"builder:gcc-x64-mac",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052612960754363712",
"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/8887182898630072560/+/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:4aa623dbb7567210",
"user_agent:luci-scheduler"
],
"updated_ts": "1582922973459158",
"url": "https://ci.chromium.org/b/8887182898630072560",
"utcnow_ts": "1582922976034528"
}
}
[20:49:36.061] Invocation finished in 1h7m10.891488211s with status SUCCEEDED