[16:07:20.255] New invocation is queued and will start shortly
[16:07:21.345] Starting the invocation (attempt 1)
[16:07:21.389] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:07:21.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:07:21.389] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9052807686520221088",
"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\":\"30cb4c78ea6563177c43f897e480d9993c38c0ed\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@30cb4c78ea6563177c43f897e480d9993c38c0ed\",\"title\":\"30cb4c78ea6563177c43f897e480d9993c38c0ed\",\"url\":\"https://gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-linux",
"scheduler_invocation_id:9052807686520221088",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[16:07:23.792] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582733241663473",
"id": "8887377624347763360",
"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\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"title\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"url\": \"https://gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582733243526642",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052807686520221088",
"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/8887377624347763360/+/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": "1582733243527196",
"url": "https://ci.chromium.org/b/8887377624347763360",
"utcnow_ts": "1582733243768193"
}
}
[16:07:23.792] Task URL: https://ci.chromium.org/b/8887377624347763360
[16:07:23.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:2:0) after 1m0s
[16:07:26.986] Received PubSub notification, asking Buildbucket for the build status
[16:07:27.115] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:23.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:2:0)
[16:08:23.768] Timer tick, asking Buildbucket for the build status
[16:08:23.935] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:23.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:4:0) after 1m0s
[16:09:23.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:4:0)
[16:09:23.975] Timer tick, asking Buildbucket for the build status
[16:09:24.139] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:24.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:5:0) after 1m0s
[16:10:24.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:5:0)
[16:10:24.185] Timer tick, asking Buildbucket for the build status
[16:10:24.387] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:24.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:6:0) after 1m0s
[16:11:24.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:6:0)
[16:11:24.367] Timer tick, asking Buildbucket for the build status
[16:11:24.553] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:24.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:7:0) after 1m0s
[16:12:24.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:7:0)
[16:12:24.575] Timer tick, asking Buildbucket for the build status
[16:12:24.775] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:24.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:8:0) after 1m0s
[16:13:24.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:8:0)
[16:13:24.799] Timer tick, asking Buildbucket for the build status
[16:13:25.034] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:25.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:9:0) after 1m0s
[16:14:25.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:9:0)
[16:14:25.073] Timer tick, asking Buildbucket for the build status
[16:14:25.286] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:25.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:10:0) after 1m0s
[16:15:25.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:10:0)
[16:15:25.346] Timer tick, asking Buildbucket for the build status
[16:15:25.443] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:25.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:11:0) after 1m0s
[16:16:25.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:11:0)
[16:16:25.525] Timer tick, asking Buildbucket for the build status
[16:16:25.670] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:25.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:12:0) after 1m0s
[16:17:25.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:12:0)
[16:17:25.718] Timer tick, asking Buildbucket for the build status
[16:17:25.822] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:25.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:13:0) after 1m0s
[16:18:25.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:13:0)
[16:18:25.857] Timer tick, asking Buildbucket for the build status
[16:18:25.948] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:25.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:14:0) after 1m0s
[16:19:26.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:14:0)
[16:19:26.011] Timer tick, asking Buildbucket for the build status
[16:19:26.109] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:26.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:15:0) after 1m0s
[16:20:26.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:15:0)
[16:20:26.286] Timer tick, asking Buildbucket for the build status
[16:20:26.407] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:26.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:16:0) after 1m0s
[16:21:26.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:16:0)
[16:21:26.553] Timer tick, asking Buildbucket for the build status
[16:21:26.695] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:26.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:17:0) after 1m0s
[16:22:26.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:17:0)
[16:22:26.829] Timer tick, asking Buildbucket for the build status
[16:22:27.153] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:27.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:18:0) after 1m0s
[16:23:27.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:18:0)
[16:23:27.277] Timer tick, asking Buildbucket for the build status
[16:23:27.545] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:27.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:19:0) after 1m0s
[16:24:27.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:19:0)
[16:24:27.644] Timer tick, asking Buildbucket for the build status
[16:24:27.969] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:27.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:20:0) after 1m0s
[16:25:27.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:20:0)
[16:25:27.992] Timer tick, asking Buildbucket for the build status
[16:25:28.126] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:28.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:21:0) after 1m0s
[16:26:28.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:21:0)
[16:26:28.218] Timer tick, asking Buildbucket for the build status
[16:26:28.350] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:28.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:22:0) after 1m0s
[16:27:28.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:22:0)
[16:27:28.446] Timer tick, asking Buildbucket for the build status
[16:27:28.701] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:28.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:23:0) after 1m0s
[16:28:28.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:23:0)
[16:28:28.728] Timer tick, asking Buildbucket for the build status
[16:28:28.833] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:28.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:24:0) after 1m0s
[16:29:28.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:24:0)
[16:29:28.865] Timer tick, asking Buildbucket for the build status
[16:29:28.963] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:28.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:25:0) after 1m0s
[16:30:29.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:25:0)
[16:30:29.018] Timer tick, asking Buildbucket for the build status
[16:30:29.163] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:29.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:26:0) after 1m0s
[16:31:29.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:26:0)
[16:31:29.204] Timer tick, asking Buildbucket for the build status
[16:31:29.826] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:29.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:27:0) after 1m0s
[16:32:29.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:27:0)
[16:32:29.928] Timer tick, asking Buildbucket for the build status
[16:32:30.250] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:30.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:28:0) after 1m0s
[16:33:30.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:28:0)
[16:33:30.359] Timer tick, asking Buildbucket for the build status
[16:33:30.509] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:30.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:29:0) after 1m0s
[16:34:30.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:29:0)
[16:34:30.672] Timer tick, asking Buildbucket for the build status
[16:34:30.772] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:30.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:30:0) after 1m0s
[16:35:30.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:30:0)
[16:35:30.919] Timer tick, asking Buildbucket for the build status
[16:35:31.218] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:31.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:31:0) after 1m0s
[16:36:31.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:31:0)
[16:36:31.309] Timer tick, asking Buildbucket for the build status
[16:36:31.582] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:31.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:32:0) after 1m0s
[16:37:31.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:32:0)
[16:37:31.785] Timer tick, asking Buildbucket for the build status
[16:37:31.964] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:31.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:33:0) after 1m0s
[16:38:32.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:33:0)
[16:38:32.088] Timer tick, asking Buildbucket for the build status
[16:38:32.782] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:32.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:34:0) after 1m0s
[16:39:32.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:34:0)
[16:39:32.823] Timer tick, asking Buildbucket for the build status
[16:39:33.254] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:33.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:35:0) after 1m0s
[16:40:33.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:35:0)
[16:40:33.351] Timer tick, asking Buildbucket for the build status
[16:40:33.775] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:33.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:36:0) after 1m0s
[16:41:33.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:36:0)
[16:41:33.827] Timer tick, asking Buildbucket for the build status
[16:41:33.917] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:33.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:37:0) after 1m0s
[16:42:33.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:37:0)
[16:42:33.999] Timer tick, asking Buildbucket for the build status
[16:42:34.116] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:34.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:38:0) after 1m0s
[16:43:34.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:38:0)
[16:43:34.149] Timer tick, asking Buildbucket for the build status
[16:43:34.400] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:34.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:39:0) after 1m0s
[16:44:34.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:39:0)
[16:44:34.434] Timer tick, asking Buildbucket for the build status
[16:44:34.806] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:34.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:40:0) after 1m0s
[16:45:34.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:40:0)
[16:45:34.936] Timer tick, asking Buildbucket for the build status
[16:45:35.029] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:35.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:41:0) after 1m0s
[16:46:35.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:41:0)
[16:46:35.097] Timer tick, asking Buildbucket for the build status
[16:46:35.313] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:35.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:42:0) after 1m0s
[16:47:35.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:42:0)
[16:47:35.435] Timer tick, asking Buildbucket for the build status
[16:47:35.606] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:35.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:43:0) after 1m0s
[16:48:35.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:43:0)
[16:48:35.622] Timer tick, asking Buildbucket for the build status
[16:48:35.994] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:35.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:44:0) after 1m0s
[16:49:36.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:44:0)
[16:49:36.095] Timer tick, asking Buildbucket for the build status
[16:49:36.204] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:36.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:45:0) after 1m0s
[16:50:36.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:45:0)
[16:50:36.326] Timer tick, asking Buildbucket for the build status
[16:50:36.558] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:36.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:46:0) after 1m0s
[16:51:36.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:46:0)
[16:51:36.654] Timer tick, asking Buildbucket for the build status
[16:51:36.872] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:36.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:47:0) after 1m0s
[16:52:36.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:47:0)
[16:52:36.899] Timer tick, asking Buildbucket for the build status
[16:52:37.099] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:37.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:48:0) after 1m0s
[16:53:37.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:48:0)
[16:53:37.124] Timer tick, asking Buildbucket for the build status
[16:53:37.227] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:37.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:49:0) after 1m0s
[16:54:37.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:49:0)
[16:54:37.293] Timer tick, asking Buildbucket for the build status
[16:54:37.431] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:37.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:50:0) after 1m0s
[16:55:37.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:50:0)
[16:55:37.508] Timer tick, asking Buildbucket for the build status
[16:55:37.723] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:37.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:51:0) after 1m0s
[16:56:37.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:51:0)
[16:56:37.777] Timer tick, asking Buildbucket for the build status
[16:56:37.961] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:37.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:52:0) after 1m0s
[16:57:38.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:52:0)
[16:57:38.016] Timer tick, asking Buildbucket for the build status
[16:57:38.192] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:38.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:53:0) after 1m0s
[16:58:38.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:53:0)
[16:58:38.403] Timer tick, asking Buildbucket for the build status
[16:58:38.574] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:38.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:54:0) after 1m0s
[16:59:38.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:54:0)
[16:59:38.661] Timer tick, asking Buildbucket for the build status
[16:59:38.777] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:38.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:55:0) after 1m0s
[17:00:38.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:55:0)
[17:00:38.950] Timer tick, asking Buildbucket for the build status
[17:00:39.068] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:39.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:56:0) after 1m0s
[17:01:39.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:56:0)
[17:01:39.102] Timer tick, asking Buildbucket for the build status
[17:01:39.247] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:57:0) after 1m0s
[17:02:39.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:57:0)
[17:02:39.348] Timer tick, asking Buildbucket for the build status
[17:02:39.452] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:39.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:58:0) after 1m0s
[17:03:39.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:58:0)
[17:03:39.552] Timer tick, asking Buildbucket for the build status
[17:03:40.052] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:59:0) after 1m0s
[17:04:40.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:59:0)
[17:04:40.122] Timer tick, asking Buildbucket for the build status
[17:04:40.294] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:40.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:60:0) after 1m0s
[17:05:40.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:60:0)
[17:05:40.340] Timer tick, asking Buildbucket for the build status
[17:05:40.522] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:40.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:61:0) after 1m0s
[17:06:40.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:61:0)
[17:06:40.599] Timer tick, asking Buildbucket for the build status
[17:06:40.899] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:40.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:62:0) after 1m0s
[17:07:40.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:62:0)
[17:07:40.954] Timer tick, asking Buildbucket for the build status
[17:07:41.136] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:41.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:63:0) after 1m0s
[17:08:41.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:63:0)
[17:08:41.170] Timer tick, asking Buildbucket for the build status
[17:08:41.453] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:41.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:64:0) after 1m0s
[17:09:41.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:64:0)
[17:09:41.503] Timer tick, asking Buildbucket for the build status
[17:09:41.938] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:41.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:65:0) after 1m0s
[17:10:42.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:65:0)
[17:10:42.029] Timer tick, asking Buildbucket for the build status
[17:10:42.204] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:66:0) after 1m0s
[17:11:42.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:66:0)
[17:11:42.376] Timer tick, asking Buildbucket for the build status
[17:11:42.669] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:42.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:67:0) after 1m0s
[17:12:42.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:67:0)
[17:12:42.775] Timer tick, asking Buildbucket for the build status
[17:12:42.926] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:42.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:68:0) after 1m0s
[17:13:42.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:68:0)
[17:13:42.950] Timer tick, asking Buildbucket for the build status
[17:13:43.092] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:69:0) after 1m0s
[17:14:43.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:69:0)
[17:14:43.202] Timer tick, asking Buildbucket for the build status
[17:14:43.670] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:70:0) after 1m0s
[17:15:43.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:70:0)
[17:15:43.791] Timer tick, asking Buildbucket for the build status
[17:15:45.131] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:71:0) after 1m0s
[17:16:45.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:71:0)
[17:16:45.157] Timer tick, asking Buildbucket for the build status
[17:16:45.533] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:45.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:72:0) after 1m0s
[17:17:45.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:72:0)
[17:17:45.618] Timer tick, asking Buildbucket for the build status
[17:17:45.728] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:45.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:73:0) after 1m0s
[17:18:45.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:73:0)
[17:18:45.763] Timer tick, asking Buildbucket for the build status
[17:18:46.121] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:46.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:74:0) after 1m0s
[17:19:46.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:74:0)
[17:19:46.246] Timer tick, asking Buildbucket for the build status
[17:19:46.350] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:46.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:75:0) after 1m0s
[17:20:46.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:75:0)
[17:20:46.493] Timer tick, asking Buildbucket for the build status
[17:20:46.810] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:46.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:76:0) after 1m0s
[17:21:47.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:76:0)
[17:21:47.002] Timer tick, asking Buildbucket for the build status
[17:21:47.387] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:47.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:77:0) after 1m0s
[17:22:47.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:77:0)
[17:22:47.461] Timer tick, asking Buildbucket for the build status
[17:22:47.909] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:47.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:78:0) after 1m0s
[17:23:48.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:78:0)
[17:23:48.034] Timer tick, asking Buildbucket for the build status
[17:23:48.185] Build 8887377624347763360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:48.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9052807686520221088:79:0) after 1m0s
[17:24:09.413] Received PubSub notification, asking Buildbucket for the build status
[17:24:09.630] Build 8887377624347763360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:24:09.630] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582737847630735",
"created_by": "project:fuchsia",
"created_ts": "1582733241663473",
"id": "8887377624347763360",
"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\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"title\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"url\": \"https://gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}}",
"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\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"title\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\", \"url\": \"https://gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-32-us-central1-c-3-2q0d\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-linux\", \"git_revision\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed,cc4c4f40a2b46e355684e450f59154cece591c39\", \"instance_id\": \"mp44WTMTP-oc5PI-RWinnh9raFqQpZHPu15BOqTBUPwC\", \"isolated\": \"ea07105935e8f1186f2e6354e2fcc88dcb7c198a\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"30cb4c78ea6563177c43f897e480d9993c38c0ed\"}, \"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-c-3-2q0d\"], \"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\": [\"4838-b69615e\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1582733245340442",
"status": "COMPLETED",
"status_changed_ts": "1582737848088015",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/30cb4c78ea6563177c43f897e480d9993c38c0ed",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9052807686520221088",
"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/8887377624347763360/+/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:4a9b123e5da9ed10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582737848098767",
"url": "https://ci.chromium.org/b/8887377624347763360",
"utcnow_ts": "1582737849620368"
}
}
[17:24:09.630] Invocation finished in 1h16m49.42181743s with status SUCCEEDED