[11:44:29.206] New invocation is queued and will start shortly
[11:44:30.274] Starting the invocation (attempt 1)
[11:44:30.314] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:44:30.314] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:44:30.314] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9053367805430894976",
"parameters_json": "{\"builder_name\":\"gcc-arm64-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/releases/gcc-9\",\"repo\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@f55bf4ddbfac3c7360cb00f3200b663c19baf504\",\"title\":\"f55bf4ddbfac3c7360cb00f3200b663c19baf504\",\"url\":\"https://gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-arm64-linux",
"scheduler_invocation_id:9053367805430894976",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[11:44:30.965] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582199070454073",
"id": "8887937743458324480",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"title\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"url\": \"https://gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582199070801278",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053367805430894976",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887937743458324480/+/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": "1582199070801798",
"url": "https://ci.chromium.org/b/8887937743458324480",
"utcnow_ts": "1582199070958725"
}
}
[11:44:30.965] Task URL: https://ci.chromium.org/b/8887937743458324480
[11:44:30.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:2:0) after 1m0s
[11:44:49.964] Received PubSub notification, asking Buildbucket for the build status
[11:44:50.056] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:31.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:2:0)
[11:45:31.002] Timer tick, asking Buildbucket for the build status
[11:45:31.324] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:31.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:4:0) after 1m0s
[11:46:31.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:4:0)
[11:46:31.302] Timer tick, asking Buildbucket for the build status
[11:46:31.482] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:31.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:5:0) after 1m0s
[11:47:31.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:5:0)
[11:47:31.487] Timer tick, asking Buildbucket for the build status
[11:47:31.599] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:31.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:6:0) after 1m0s
[11:48:31.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:6:0)
[11:48:31.683] Timer tick, asking Buildbucket for the build status
[11:48:31.862] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:31.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:7:0) after 1m0s
[11:49:31.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:7:0)
[11:49:31.930] Timer tick, asking Buildbucket for the build status
[11:49:32.075] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:32.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:8:0) after 1m0s
[11:50:32.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:8:0)
[11:50:32.134] Timer tick, asking Buildbucket for the build status
[11:50:32.561] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:32.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:9:0) after 1m0s
[11:51:32.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:9:0)
[11:51:32.600] Timer tick, asking Buildbucket for the build status
[11:51:32.737] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:32.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:10:0) after 1m0s
[11:52:32.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:10:0)
[11:52:32.757] Timer tick, asking Buildbucket for the build status
[11:52:32.822] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:32.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:11:0) after 1m0s
[11:53:32.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:11:0)
[11:53:32.918] Timer tick, asking Buildbucket for the build status
[11:53:32.991] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:32.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:12:0) after 1m0s
[11:54:33.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:12:0)
[11:54:33.088] Timer tick, asking Buildbucket for the build status
[11:54:33.404] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:33.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:13:0) after 1m0s
[11:55:33.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:13:0)
[11:55:33.482] Timer tick, asking Buildbucket for the build status
[11:55:33.581] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:33.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:14:0) after 1m0s
[11:56:33.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:14:0)
[11:56:33.595] Timer tick, asking Buildbucket for the build status
[11:56:33.780] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:33.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:15:0) after 1m0s
[11:57:33.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:15:0)
[11:57:33.836] Timer tick, asking Buildbucket for the build status
[11:57:33.964] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:33.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:16:0) after 1m0s
[11:58:34.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:16:0)
[11:58:34.023] Timer tick, asking Buildbucket for the build status
[11:58:34.156] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:34.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:17:0) after 1m0s
[11:59:34.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:17:0)
[11:59:34.238] Timer tick, asking Buildbucket for the build status
[11:59:34.587] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:34.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:18:0) after 1m0s
[12:00:34.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:18:0)
[12:00:34.576] Timer tick, asking Buildbucket for the build status
[12:00:34.831] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:34.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:19:0) after 1m0s
[12:01:34.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:19:0)
[12:01:34.913] Timer tick, asking Buildbucket for the build status
[12:01:35.008] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:35.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:20:0) after 1m0s
[12:02:35.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:20:0)
[12:02:35.116] Timer tick, asking Buildbucket for the build status
[12:02:35.339] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:35.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:21:0) after 1m0s
[12:03:35.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:21:0)
[12:03:35.377] Timer tick, asking Buildbucket for the build status
[12:03:35.446] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:35.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:22:0) after 1m0s
[12:04:35.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:22:0)
[12:04:35.512] Timer tick, asking Buildbucket for the build status
[12:04:35.584] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:35.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:23:0) after 1m0s
[12:05:35.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:23:0)
[12:05:35.628] Timer tick, asking Buildbucket for the build status
[12:05:35.942] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:35.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:24:0) after 1m0s
[12:06:36.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:24:0)
[12:06:36.013] Timer tick, asking Buildbucket for the build status
[12:06:36.122] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:36.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:25:0) after 1m0s
[12:07:36.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:25:0)
[12:07:36.179] Timer tick, asking Buildbucket for the build status
[12:07:36.519] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:36.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:26:0) after 1m0s
[12:08:36.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:26:0)
[12:08:36.539] Timer tick, asking Buildbucket for the build status
[12:08:36.662] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:36.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:27:0) after 1m0s
[12:09:36.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:27:0)
[12:09:36.698] Timer tick, asking Buildbucket for the build status
[12:09:36.819] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:36.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:28:0) after 1m0s
[12:10:36.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:28:0)
[12:10:36.803] Timer tick, asking Buildbucket for the build status
[12:10:37.012] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:37.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:29:0) after 1m0s
[12:11:37.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:29:0)
[12:11:37.096] Timer tick, asking Buildbucket for the build status
[12:11:37.205] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:37.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:30:0) after 1m0s
[12:12:37.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:30:0)
[12:12:37.291] Timer tick, asking Buildbucket for the build status
[12:12:37.425] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:37.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:31:0) after 1m0s
[12:13:37.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:31:0)
[12:13:37.444] Timer tick, asking Buildbucket for the build status
[12:13:37.587] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:37.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:32:0) after 1m0s
[12:14:37.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:32:0)
[12:14:37.604] Timer tick, asking Buildbucket for the build status
[12:14:37.754] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:37.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:33:0) after 1m0s
[12:15:37.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:33:0)
[12:15:37.831] Timer tick, asking Buildbucket for the build status
[12:15:38.064] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:38.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:34:0) after 1m0s
[12:16:38.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:34:0)
[12:16:38.102] Timer tick, asking Buildbucket for the build status
[12:16:38.223] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:38.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:35:0) after 1m0s
[12:17:38.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:35:0)
[12:17:38.241] Timer tick, asking Buildbucket for the build status
[12:17:38.438] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:38.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:36:0) after 1m0s
[12:18:38.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:36:0)
[12:18:38.499] Timer tick, asking Buildbucket for the build status
[12:18:38.589] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:38.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:37:0) after 1m0s
[12:19:38.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:37:0)
[12:19:38.683] Timer tick, asking Buildbucket for the build status
[12:19:38.805] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:38.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:38:0) after 1m0s
[12:20:38.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:38:0)
[12:20:38.888] Timer tick, asking Buildbucket for the build status
[12:20:39.325] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:39.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:39:0) after 1m0s
[12:21:39.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:39:0)
[12:21:39.346] Timer tick, asking Buildbucket for the build status
[12:21:39.702] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:39.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:40:0) after 1m0s
[12:22:39.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:40:0)
[12:22:39.770] Timer tick, asking Buildbucket for the build status
[12:22:40.055] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:40.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:41:0) after 1m0s
[12:23:40.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:41:0)
[12:23:40.111] Timer tick, asking Buildbucket for the build status
[12:23:40.281] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:40.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:42:0) after 1m0s
[12:24:40.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:42:0)
[12:24:40.325] Timer tick, asking Buildbucket for the build status
[12:24:40.675] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:40.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:43:0) after 1m0s
[12:25:40.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:43:0)
[12:25:40.716] Timer tick, asking Buildbucket for the build status
[12:25:40.860] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:40.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:44:0) after 1m0s
[12:26:40.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:44:0)
[12:26:40.856] Timer tick, asking Buildbucket for the build status
[12:26:41.024] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:41.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:45:0) after 1m0s
[12:27:41.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:45:0)
[12:27:41.077] Timer tick, asking Buildbucket for the build status
[12:27:41.242] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:41.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:46:0) after 1m0s
[12:28:41.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:46:0)
[12:28:41.295] Timer tick, asking Buildbucket for the build status
[12:28:41.743] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:41.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:47:0) after 1m0s
[12:29:41.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:47:0)
[12:29:41.763] Timer tick, asking Buildbucket for the build status
[12:29:42.014] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:42.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:48:0) after 1m0s
[12:30:42.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:48:0)
[12:30:42.073] Timer tick, asking Buildbucket for the build status
[12:30:42.347] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:42.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:49:0) after 1m0s
[12:31:42.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:49:0)
[12:31:42.509] Timer tick, asking Buildbucket for the build status
[12:31:42.662] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:42.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:50:0) after 1m0s
[12:32:42.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:50:0)
[12:32:42.711] Timer tick, asking Buildbucket for the build status
[12:32:42.931] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:42.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:51:0) after 1m0s
[12:33:43.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:51:0)
[12:33:43.095] Timer tick, asking Buildbucket for the build status
[12:33:43.253] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:43.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:52:0) after 1m0s
[12:34:43.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:52:0)
[12:34:43.369] Timer tick, asking Buildbucket for the build status
[12:34:43.518] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:43.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:53:0) after 1m0s
[12:35:43.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:53:0)
[12:35:43.718] Timer tick, asking Buildbucket for the build status
[12:35:44.284] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:44.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:54:0) after 1m0s
[12:36:44.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:54:0)
[12:36:44.342] Timer tick, asking Buildbucket for the build status
[12:36:44.422] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:44.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:55:0) after 1m0s
[12:37:44.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:55:0)
[12:37:44.584] Timer tick, asking Buildbucket for the build status
[12:37:45.037] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:45.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:56:0) after 1m0s
[12:38:45.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:56:0)
[12:38:45.146] Timer tick, asking Buildbucket for the build status
[12:38:45.451] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:45.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:57:0) after 1m0s
[12:39:45.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:57:0)
[12:39:45.593] Timer tick, asking Buildbucket for the build status
[12:39:45.697] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:45.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:58:0) after 1m0s
[12:40:45.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:58:0)
[12:40:45.727] Timer tick, asking Buildbucket for the build status
[12:40:45.928] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:45.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:59:0) after 1m0s
[12:41:45.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:59:0)
[12:41:45.946] Timer tick, asking Buildbucket for the build status
[12:41:46.123] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:46.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:60:0) after 1m0s
[12:42:46.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:60:0)
[12:42:46.152] Timer tick, asking Buildbucket for the build status
[12:42:46.360] Build 8887937743458324480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:46.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-arm64-linux:9053367805430894976:61:0) after 1m0s
[12:43:38.159] Received PubSub notification, asking Buildbucket for the build status
[12:43:38.373] Build 8887937743458324480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:43:38.373] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582202617506108",
"created_by": "project:fuchsia",
"created_ts": "1582199070454073",
"id": "8887937743458324480",
"parameters_json": "{\"builder_name\": \"gcc-arm64-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/releases/gcc-9\", \"repo\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"title\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"url\": \"https://gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}}",
"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\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"title\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\", \"url\": \"https://gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build413-a9\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-arm64-linux\", \"git_revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504,d7445728b14851cb8535a6a9fa2995817dea2e60\", \"instance_id\": \"k2stSvkZ_gcwYljIWPUzQ3hF6wRoG-zMtKH8v_KyMZgC\", \"isolated\": \"99186d4a52b790ff2c059438f0dcc9e0a4c99deb\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-arm64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"f55bf4ddbfac3c7360cb00f3200b663c19baf504\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"vpython\"], \"cores\": [\"256\"], \"cpu\": [\"arm\", \"arm-64\", \"arm64\", \"arm64-64\"], \"gce\": [\"0\"], \"gpu\": [\"1a03\", \"1a03:2000\"], \"id\": [\"build413-a9\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-highcpu-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-18.04\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.15+\"], \"server_version\": [\"4825-3d129d6\"], \"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": "1582199088870778",
"status": "COMPLETED",
"status_changed_ts": "1582202617885359",
"tags": [
"builder:gcc-arm64-linux",
"buildset:commit/git/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/f55bf4ddbfac3c7360cb00f3200b663c19baf504",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053367805430894976",
"scheduler_job_id:fuchsia/prod-gcc-arm64-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8887937743458324480/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:gcc_toolchain",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4a7b3b6932c85110",
"user_agent:luci-scheduler"
],
"updated_ts": "1582202617900752",
"url": "https://ci.chromium.org/b/8887937743458324480",
"utcnow_ts": "1582202618366313"
}
}
[12:43:38.373] Invocation finished in 59m9.183485529s with status SUCCEEDED