[19:56:22.988] New invocation is queued and will start shortly
[19:56:24.120] Starting the invocation (attempt 1)
[19:56:24.193] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:56:24.193] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:56:24.193] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9052612082257271408",
"parameters_json": "{\"builder_name\":\"gcc-x64-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/releases/gcc-9\",\"repo\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"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-linux",
"scheduler_invocation_id:9052612082257271408",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[19:56:24.952] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582919784313238",
"id": "8887182020202072656",
"parameters_json": "{\"builder_name\": \"gcc-x64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"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": "1582919784774022",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052612082257271408",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887182020202072656/+/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": "1582919784774187",
"url": "https://ci.chromium.org/b/8887182020202072656",
"utcnow_ts": "1582919784942621"
}
}
[19:56:24.952] Task URL: https://ci.chromium.org/b/8887182020202072656
[19:56:24.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:2:0) after 1m0s
[19:56:27.113] Received PubSub notification, asking Buildbucket for the build status
[19:56:27.360] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:24.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:2:0)
[19:57:24.968] Timer tick, asking Buildbucket for the build status
[19:57:25.351] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:25.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:4:0) after 1m0s
[19:58:25.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:4:0)
[19:58:25.503] Timer tick, asking Buildbucket for the build status
[19:58:26.222] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:26.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:5:0) after 1m0s
[19:59:26.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:5:0)
[19:59:26.243] Timer tick, asking Buildbucket for the build status
[19:59:27.538] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:27.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:6:0) after 1m0s
[20:00:27.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:6:0)
[20:00:27.598] Timer tick, asking Buildbucket for the build status
[20:00:27.770] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:27.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:7:0) after 1m0s
[20:01:27.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:7:0)
[20:01:27.809] Timer tick, asking Buildbucket for the build status
[20:01:27.969] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:27.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:8:0) after 1m0s
[20:02:27.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:8:0)
[20:02:27.989] Timer tick, asking Buildbucket for the build status
[20:02:28.440] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:28.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:9:0) after 1m0s
[20:03:28.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:9:0)
[20:03:28.494] Timer tick, asking Buildbucket for the build status
[20:03:29.265] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:29.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:10:0) after 1m0s
[20:04:29.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:10:0)
[20:04:29.293] Timer tick, asking Buildbucket for the build status
[20:04:31.053] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:31.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:11:0) after 1m0s
[20:05:31.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:11:0)
[20:05:31.085] Timer tick, asking Buildbucket for the build status
[20:05:31.402] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:31.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:12:0) after 1m0s
[20:06:31.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:12:0)
[20:06:31.426] Timer tick, asking Buildbucket for the build status
[20:06:32.441] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:32.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:13:0) after 1m0s
[20:07:32.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:13:0)
[20:07:32.463] Timer tick, asking Buildbucket for the build status
[20:07:32.884] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:32.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:14:0) after 1m0s
[20:08:32.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:14:0)
[20:08:32.954] Timer tick, asking Buildbucket for the build status
[20:08:33.900] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:33.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:15:0) after 1m0s
[20:09:33.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:15:0)
[20:09:33.940] Timer tick, asking Buildbucket for the build status
[20:09:34.359] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:34.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:16:0) after 1m0s
[20:10:34.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:16:0)
[20:10:34.445] Timer tick, asking Buildbucket for the build status
[20:10:34.786] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:34.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:17:0) after 1m0s
[20:11:34.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:17:0)
[20:11:34.877] Timer tick, asking Buildbucket for the build status
[20:11:35.051] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:35.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:18:0) after 1m0s
[20:12:35.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:18:0)
[20:12:35.059] Timer tick, asking Buildbucket for the build status
[20:12:35.175] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:35.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:19:0) after 1m0s
[20:13:35.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:19:0)
[20:13:35.246] Timer tick, asking Buildbucket for the build status
[20:13:36.478] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:36.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:20:0) after 1m0s
[20:14:36.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:20:0)
[20:14:36.678] Timer tick, asking Buildbucket for the build status
[20:14:37.485] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:37.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:21:0) after 1m0s
[20:15:37.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:21:0)
[20:15:37.529] Timer tick, asking Buildbucket for the build status
[20:15:37.917] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:37.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:22:0) after 1m0s
[20:16:37.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:22:0)
[20:16:37.995] Timer tick, asking Buildbucket for the build status
[20:16:38.337] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:38.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:23:0) after 1m0s
[20:17:38.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:23:0)
[20:17:38.451] Timer tick, asking Buildbucket for the build status
[20:17:38.781] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:38.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:24:0) after 1m0s
[20:18:38.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:24:0)
[20:18:38.822] Timer tick, asking Buildbucket for the build status
[20:18:39.857] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:39.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:25:0) after 1m0s
[20:19:40.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:25:0)
[20:19:40.001] Timer tick, asking Buildbucket for the build status
[20:19:40.289] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:26:0) after 1m0s
[20:20:40.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:26:0)
[20:20:40.317] Timer tick, asking Buildbucket for the build status
[20:20:40.451] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:27:0) after 1m0s
[20:21:40.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:27:0)
[20:21:40.558] Timer tick, asking Buildbucket for the build status
[20:21:40.952] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:40.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:28:0) after 1m0s
[20:22:41.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:28:0)
[20:22:41.077] Timer tick, asking Buildbucket for the build status
[20:22:41.484] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:29:0) after 1m0s
[20:23:41.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:29:0)
[20:23:41.525] Timer tick, asking Buildbucket for the build status
[20:23:42.570] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:42.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:30:0) after 1m0s
[20:24:42.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:30:0)
[20:24:42.633] Timer tick, asking Buildbucket for the build status
[20:24:42.986] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:31:0) after 1m0s
[20:25:43.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:31:0)
[20:25:43.041] Timer tick, asking Buildbucket for the build status
[20:25:43.323] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:43.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:32:0) after 1m0s
[20:26:43.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:32:0)
[20:26:43.349] Timer tick, asking Buildbucket for the build status
[20:26:43.537] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:43.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:33:0) after 1m0s
[20:27:43.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:33:0)
[20:27:43.620] Timer tick, asking Buildbucket for the build status
[20:27:44.134] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:44.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:34:0) after 1m0s
[20:28:44.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:34:0)
[20:28:44.189] Timer tick, asking Buildbucket for the build status
[20:28:45.466] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:45.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:35:0) after 1m0s
[20:29:45.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:35:0)
[20:29:45.678] Timer tick, asking Buildbucket for the build status
[20:29:45.852] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:45.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:36:0) after 1m0s
[20:30:46.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:36:0)
[20:30:46.028] Timer tick, asking Buildbucket for the build status
[20:30:46.137] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:46.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:37:0) after 1m0s
[20:31:46.244] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:37:0)
[20:31:46.244] Timer tick, asking Buildbucket for the build status
[20:31:46.483] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:46.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:38:0) after 1m0s
[20:32:46.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:38:0)
[20:32:46.550] Timer tick, asking Buildbucket for the build status
[20:32:46.666] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:46.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:39:0) after 1m0s
[20:33:46.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:39:0)
[20:33:46.753] Timer tick, asking Buildbucket for the build status
[20:33:46.891] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:46.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:40:0) after 1m0s
[20:34:46.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:40:0)
[20:34:46.909] Timer tick, asking Buildbucket for the build status
[20:34:47.103] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:47.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:41:0) after 1m0s
[20:35:47.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:41:0)
[20:35:47.207] Timer tick, asking Buildbucket for the build status
[20:35:47.373] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:47.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:42:0) after 1m0s
[20:36:47.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:42:0)
[20:36:47.489] Timer tick, asking Buildbucket for the build status
[20:36:47.750] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:47.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:43:0) after 1m0s
[20:37:47.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:43:0)
[20:37:47.942] Timer tick, asking Buildbucket for the build status
[20:37:48.105] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:48.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:44:0) after 1m0s
[20:38:48.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:44:0)
[20:38:48.309] Timer tick, asking Buildbucket for the build status
[20:38:48.783] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:48.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:45:0) after 1m0s
[20:39:48.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:45:0)
[20:39:48.779] Timer tick, asking Buildbucket for the build status
[20:39:48.882] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:48.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:46:0) after 1m0s
[20:40:49.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:46:0)
[20:40:49.123] Timer tick, asking Buildbucket for the build status
[20:40:49.334] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:49.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:47:0) after 1m0s
[20:41:49.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:47:0)
[20:41:49.755] Timer tick, asking Buildbucket for the build status
[20:41:49.934] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:49.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:48:0) after 1m0s
[20:42:50.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:48:0)
[20:42:50.144] Timer tick, asking Buildbucket for the build status
[20:42:50.814] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:50.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:49:0) after 1m0s
[20:43:50.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:49:0)
[20:43:50.899] Timer tick, asking Buildbucket for the build status
[20:43:51.298] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:51.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:50:0) after 1m0s
[20:44:51.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:50:0)
[20:44:51.412] Timer tick, asking Buildbucket for the build status
[20:44:51.529] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:51.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:51:0) after 1m0s
[20:45:51.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:51:0)
[20:45:51.588] Timer tick, asking Buildbucket for the build status
[20:45:52.041] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:52.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:52:0) after 1m0s
[20:46:52.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:52:0)
[20:46:52.117] Timer tick, asking Buildbucket for the build status
[20:46:52.340] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:52.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:53:0) after 1m0s
[20:47:52.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:53:0)
[20:47:52.418] Timer tick, asking Buildbucket for the build status
[20:47:52.625] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:52.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:54:0) after 1m0s
[20:48:52.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:54:0)
[20:48:52.687] Timer tick, asking Buildbucket for the build status
[20:48:52.863] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:52.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:55:0) after 1m0s
[20:49:52.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:55:0)
[20:49:52.921] Timer tick, asking Buildbucket for the build status
[20:49:53.202] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:53.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:56:0) after 1m0s
[20:50:53.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:56:0)
[20:50:53.239] Timer tick, asking Buildbucket for the build status
[20:50:53.455] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:53.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:57:0) after 1m0s
[20:51:53.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:57:0)
[20:51:53.692] Timer tick, asking Buildbucket for the build status
[20:51:53.951] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:53.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:58:0) after 1m0s
[20:52:54.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:58:0)
[20:52:54.186] Timer tick, asking Buildbucket for the build status
[20:52:54.348] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:54.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:59:0) after 1m0s
[20:53:54.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:59:0)
[20:53:54.477] Timer tick, asking Buildbucket for the build status
[20:53:54.677] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:54.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:60:0) after 1m0s
[20:54:54.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:60:0)
[20:54:54.723] Timer tick, asking Buildbucket for the build status
[20:54:54.864] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:54.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:61:0) after 1m0s
[20:55:55.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:61:0)
[20:55:55.028] Timer tick, asking Buildbucket for the build status
[20:55:55.143] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:55.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:62:0) after 1m0s
[20:56:55.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:62:0)
[20:56:55.399] Timer tick, asking Buildbucket for the build status
[20:56:55.766] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:55.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:63:0) after 1m0s
[20:57:55.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:63:0)
[20:57:55.949] Timer tick, asking Buildbucket for the build status
[20:57:56.069] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:56.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:64:0) after 1m0s
[20:58:56.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:64:0)
[20:58:56.163] Timer tick, asking Buildbucket for the build status
[20:58:56.330] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:56.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:65:0) after 1m0s
[20:59:56.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:65:0)
[20:59:56.460] Timer tick, asking Buildbucket for the build status
[20:59:56.573] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:56.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:66:0) after 1m0s
[21:00:56.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:66:0)
[21:00:56.669] Timer tick, asking Buildbucket for the build status
[21:00:56.876] Build 8887182020202072656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:56.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052612082257271408:67:0) after 1m0s
[21:01:50.556] Received PubSub notification, asking Buildbucket for the build status
[21:01:50.744] Build 8887182020202072656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:01:50.744] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582923709621660",
"created_by": "project:fuchsia",
"created_ts": "1582919784313238",
"id": "8887182020202072656",
"parameters_json": "{\"builder_name\": \"gcc-x64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"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\": \"fuchsia-prod-32-us-central1-a-0-acd8\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-linux\", \"isolated\": \"e8cdba58501efc135d1a337709e74bf3293f289b\", \"mastername\": \"gcc\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"9179ae6a0df27e3d3830ee42f755dc7d76cb0423\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-32-us-central1-a-0-acd8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20200212-956c4dc\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Debian\", \"Debian-9.11\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"role\": [\"builder\"], \"scale_group\": [\"fuchsia-prod-32\"], \"server_version\": [\"4840-d3c86f9\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1582919786459938",
"status": "COMPLETED",
"status_changed_ts": "1582923709995871",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/9179ae6a0df27e3d3830ee42f755dc7d76cb0423",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052612082257271408",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887182020202072656/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:gcc_toolchain",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4aa630a301237a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582923710051674",
"url": "https://ci.chromium.org/b/8887182020202072656",
"utcnow_ts": "1582923710729727"
}
}
[21:01:50.744] Invocation finished in 1h5m27.774197539s with status SUCCEEDED