[11:43:58.899] New invocation is queued and will start shortly
[11:44:00.519] Starting the invocation (attempt 1)
[11:44:00.574] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:44:00.574] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:44:00.575] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9053549031160392320",
"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\":\"560139481239e37a1cc878fb90805fa5e94f623c\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@560139481239e37a1cc878fb90805fa5e94f623c\",\"title\":\"560139481239e37a1cc878fb90805fa5e94f623c\",\"url\":\"https://gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"560139481239e37a1cc878fb90805fa5e94f623c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-linux",
"scheduler_invocation_id:9053549031160392320",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/560139481239e37a1cc878fb90805fa5e94f623c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[11:44:01.377] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582026240774390",
"id": "8888118968512688992",
"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\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@560139481239e37a1cc878fb90805fa5e94f623c\", \"title\": \"560139481239e37a1cc878fb90805fa5e94f623c\", \"url\": \"https://gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582026241169490",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/560139481239e37a1cc878fb90805fa5e94f623c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053549031160392320",
"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/8888118968512688992/+/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": "1582026241169590",
"url": "https://ci.chromium.org/b/8888118968512688992",
"utcnow_ts": "1582026241364300"
}
}
[11:44:01.377] Task URL: https://ci.chromium.org/b/8888118968512688992
[11:44:01.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:2:0) after 1m0s
[11:44:05.424] Received PubSub notification, asking Buildbucket for the build status
[11:44:05.628] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:01.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:2:0)
[11:45:01.405] Timer tick, asking Buildbucket for the build status
[11:45:01.513] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:01.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:4:0) after 1m0s
[11:46:01.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:4:0)
[11:46:01.620] Timer tick, asking Buildbucket for the build status
[11:46:01.890] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:01.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:5:0) after 1m0s
[11:47:01.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:5:0)
[11:47:01.914] Timer tick, asking Buildbucket for the build status
[11:47:02.007] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:02.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:6:0) after 1m0s
[11:48:02.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:6:0)
[11:48:02.017] Timer tick, asking Buildbucket for the build status
[11:48:02.215] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:02.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:7:0) after 1m0s
[11:49:02.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:7:0)
[11:49:02.301] Timer tick, asking Buildbucket for the build status
[11:49:02.414] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:02.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:8:0) after 1m0s
[11:50:02.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:8:0)
[11:50:02.439] Timer tick, asking Buildbucket for the build status
[11:50:02.586] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:02.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:9:0) after 1m0s
[11:51:02.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:9:0)
[11:51:02.626] Timer tick, asking Buildbucket for the build status
[11:51:03.096] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:03.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:10:0) after 1m0s
[11:52:03.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:10:0)
[11:52:03.137] Timer tick, asking Buildbucket for the build status
[11:52:03.556] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:03.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:11:0) after 1m0s
[11:53:03.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:11:0)
[11:53:03.754] Timer tick, asking Buildbucket for the build status
[11:53:03.923] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:03.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:12:0) after 1m0s
[11:54:04.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:12:0)
[11:54:04.043] Timer tick, asking Buildbucket for the build status
[11:54:04.165] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:04.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:13:0) after 1m0s
[11:55:04.219] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:13:0)
[11:55:04.219] Timer tick, asking Buildbucket for the build status
[11:55:04.382] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:04.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:14:0) after 1m0s
[11:56:04.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:14:0)
[11:56:04.487] Timer tick, asking Buildbucket for the build status
[11:56:04.613] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:04.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:15:0) after 1m0s
[11:57:04.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:15:0)
[11:57:04.696] Timer tick, asking Buildbucket for the build status
[11:57:05.043] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:05.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:16:0) after 1m0s
[11:58:05.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:16:0)
[11:58:05.095] Timer tick, asking Buildbucket for the build status
[11:58:05.346] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:05.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:17:0) after 1m0s
[11:59:05.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:17:0)
[11:59:05.402] Timer tick, asking Buildbucket for the build status
[11:59:05.532] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:05.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:18:0) after 1m0s
[12:00:05.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:18:0)
[12:00:05.595] Timer tick, asking Buildbucket for the build status
[12:00:05.733] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:05.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:19:0) after 1m0s
[12:01:05.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:19:0)
[12:01:05.894] Timer tick, asking Buildbucket for the build status
[12:01:06.184] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:06.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:20:0) after 1m0s
[12:02:06.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:20:0)
[12:02:06.326] Timer tick, asking Buildbucket for the build status
[12:02:06.593] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:06.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:21:0) after 1m0s
[12:03:06.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:21:0)
[12:03:06.628] Timer tick, asking Buildbucket for the build status
[12:03:06.971] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:06.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:22:0) after 1m0s
[12:04:06.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:22:0)
[12:04:06.999] Timer tick, asking Buildbucket for the build status
[12:04:07.131] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:07.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:23:0) after 1m0s
[12:05:07.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:23:0)
[12:05:07.153] Timer tick, asking Buildbucket for the build status
[12:05:07.306] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:07.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:24:0) after 1m0s
[12:06:07.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:24:0)
[12:06:07.422] Timer tick, asking Buildbucket for the build status
[12:06:07.555] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:07.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:25:0) after 1m0s
[12:07:07.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:25:0)
[12:07:07.649] Timer tick, asking Buildbucket for the build status
[12:07:07.785] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:07.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:26:0) after 1m0s
[12:08:07.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:26:0)
[12:08:07.859] Timer tick, asking Buildbucket for the build status
[12:08:08.059] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:08.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:27:0) after 1m0s
[12:09:08.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:27:0)
[12:09:08.196] Timer tick, asking Buildbucket for the build status
[12:09:08.485] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:08.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:28:0) after 1m0s
[12:10:08.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:28:0)
[12:10:08.620] Timer tick, asking Buildbucket for the build status
[12:10:08.991] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:08.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:29:0) after 1m0s
[12:11:09.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:29:0)
[12:11:09.032] Timer tick, asking Buildbucket for the build status
[12:11:09.254] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:09.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:30:0) after 1m0s
[12:12:09.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:30:0)
[12:12:09.246] Timer tick, asking Buildbucket for the build status
[12:12:09.369] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:09.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:31:0) after 1m0s
[12:13:09.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:31:0)
[12:13:09.443] Timer tick, asking Buildbucket for the build status
[12:13:09.748] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:09.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:32:0) after 1m0s
[12:14:09.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:32:0)
[12:14:09.785] Timer tick, asking Buildbucket for the build status
[12:14:10.180] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:10.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:33:0) after 1m0s
[12:15:10.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:33:0)
[12:15:10.242] Timer tick, asking Buildbucket for the build status
[12:15:10.498] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:10.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:34:0) after 1m0s
[12:16:10.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:34:0)
[12:16:10.557] Timer tick, asking Buildbucket for the build status
[12:16:10.764] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:10.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:35:0) after 1m0s
[12:17:10.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:35:0)
[12:17:10.790] Timer tick, asking Buildbucket for the build status
[12:17:11.394] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:36:0) after 1m0s
[12:18:11.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:36:0)
[12:18:11.416] Timer tick, asking Buildbucket for the build status
[12:18:11.534] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:11.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:37:0) after 1m0s
[12:19:11.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:37:0)
[12:19:11.520] Timer tick, asking Buildbucket for the build status
[12:19:11.633] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:11.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:38:0) after 1m0s
[12:20:11.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:38:0)
[12:20:11.715] Timer tick, asking Buildbucket for the build status
[12:20:11.806] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:11.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:39:0) after 1m0s
[12:21:11.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:39:0)
[12:21:11.883] Timer tick, asking Buildbucket for the build status
[12:21:11.990] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:11.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:40:0) after 1m0s
[12:22:12.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:40:0)
[12:22:12.027] Timer tick, asking Buildbucket for the build status
[12:22:12.505] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:12.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:41:0) after 1m0s
[12:23:12.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:41:0)
[12:23:12.594] Timer tick, asking Buildbucket for the build status
[12:23:12.713] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:12.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:42:0) after 1m0s
[12:24:12.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:42:0)
[12:24:12.753] Timer tick, asking Buildbucket for the build status
[12:24:12.972] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:12.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:43:0) after 1m0s
[12:25:13.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:43:0)
[12:25:13.039] Timer tick, asking Buildbucket for the build status
[12:25:13.203] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:13.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:44:0) after 1m0s
[12:26:13.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:44:0)
[12:26:13.449] Timer tick, asking Buildbucket for the build status
[12:26:13.790] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:13.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:45:0) after 1m0s
[12:27:13.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:45:0)
[12:27:13.869] Timer tick, asking Buildbucket for the build status
[12:27:13.989] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:13.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:46:0) after 1m0s
[12:28:14.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:46:0)
[12:28:14.167] Timer tick, asking Buildbucket for the build status
[12:28:14.298] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:14.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:47:0) after 1m0s
[12:29:14.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:47:0)
[12:29:14.399] Timer tick, asking Buildbucket for the build status
[12:29:14.784] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:14.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:48:0) after 1m0s
[12:30:14.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:48:0)
[12:30:14.854] Timer tick, asking Buildbucket for the build status
[12:30:14.982] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:14.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:49:0) after 1m0s
[12:31:15.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:49:0)
[12:31:15.119] Timer tick, asking Buildbucket for the build status
[12:31:15.271] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:15.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:50:0) after 1m0s
[12:32:15.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:50:0)
[12:32:15.311] Timer tick, asking Buildbucket for the build status
[12:32:15.458] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:15.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:51:0) after 1m0s
[12:33:15.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:51:0)
[12:33:15.653] Timer tick, asking Buildbucket for the build status
[12:33:15.773] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:15.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:52:0) after 1m0s
[12:34:15.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:52:0)
[12:34:15.827] Timer tick, asking Buildbucket for the build status
[12:34:15.933] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:15.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:53:0) after 1m0s
[12:35:16.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:53:0)
[12:35:16.003] Timer tick, asking Buildbucket for the build status
[12:35:16.214] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:16.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:54:0) after 1m0s
[12:36:16.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:54:0)
[12:36:16.411] Timer tick, asking Buildbucket for the build status
[12:36:16.534] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:16.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:55:0) after 1m0s
[12:37:16.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:55:0)
[12:37:16.731] Timer tick, asking Buildbucket for the build status
[12:37:17.131] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:17.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:56:0) after 1m0s
[12:38:17.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:56:0)
[12:38:17.382] Timer tick, asking Buildbucket for the build status
[12:38:17.892] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:17.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:57:0) after 1m0s
[12:39:17.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:57:0)
[12:39:17.996] Timer tick, asking Buildbucket for the build status
[12:39:18.234] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:18.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:58:0) after 1m0s
[12:40:18.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:58:0)
[12:40:18.384] Timer tick, asking Buildbucket for the build status
[12:40:18.641] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:18.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:59:0) after 1m0s
[12:41:18.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:59:0)
[12:41:18.726] Timer tick, asking Buildbucket for the build status
[12:41:18.843] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:18.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:60:0) after 1m0s
[12:42:19.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:60:0)
[12:42:19.106] Timer tick, asking Buildbucket for the build status
[12:42:19.204] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:19.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:61:0) after 1m0s
[12:43:19.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:61:0)
[12:43:19.328] Timer tick, asking Buildbucket for the build status
[12:43:19.438] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:19.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:62:0) after 1m0s
[12:44:19.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:62:0)
[12:44:19.620] Timer tick, asking Buildbucket for the build status
[12:44:19.858] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:19.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:63:0) after 1m0s
[12:45:20.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:63:0)
[12:45:20.016] Timer tick, asking Buildbucket for the build status
[12:45:20.124] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:20.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:64:0) after 1m0s
[12:46:20.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:64:0)
[12:46:20.223] Timer tick, asking Buildbucket for the build status
[12:46:20.572] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:20.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:65:0) after 1m0s
[12:47:20.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:65:0)
[12:47:20.767] Timer tick, asking Buildbucket for the build status
[12:47:21.970] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:21.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:66:0) after 1m0s
[12:48:22.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:66:0)
[12:48:22.086] Timer tick, asking Buildbucket for the build status
[12:48:22.202] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:22.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:67:0) after 1m0s
[12:49:22.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:67:0)
[12:49:22.272] Timer tick, asking Buildbucket for the build status
[12:49:22.391] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:22.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:68:0) after 1m0s
[12:50:22.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:68:0)
[12:50:22.413] Timer tick, asking Buildbucket for the build status
[12:50:22.549] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:22.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:69:0) after 1m0s
[12:51:22.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:69:0)
[12:51:22.732] Timer tick, asking Buildbucket for the build status
[12:51:22.998] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:22.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:70:0) after 1m0s
[12:52:23.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:70:0)
[12:52:23.088] Timer tick, asking Buildbucket for the build status
[12:52:23.221] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:23.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:71:0) after 1m0s
[12:53:23.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:71:0)
[12:53:23.351] Timer tick, asking Buildbucket for the build status
[12:53:23.511] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:23.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:72:0) after 1m0s
[12:54:23.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:72:0)
[12:54:23.524] Timer tick, asking Buildbucket for the build status
[12:54:23.888] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:23.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:73:0) after 1m0s
[12:55:23.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:73:0)
[12:55:23.969] Timer tick, asking Buildbucket for the build status
[12:55:24.239] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:24.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:74:0) after 1m0s
[12:56:24.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:74:0)
[12:56:24.376] Timer tick, asking Buildbucket for the build status
[12:56:24.563] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:24.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:75:0) after 1m0s
[12:57:24.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:75:0)
[12:57:24.730] Timer tick, asking Buildbucket for the build status
[12:57:24.855] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:24.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:76:0) after 1m0s
[12:58:24.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:76:0)
[12:58:24.979] Timer tick, asking Buildbucket for the build status
[12:58:25.116] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:25.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:77:0) after 1m0s
[12:59:25.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:77:0)
[12:59:25.186] Timer tick, asking Buildbucket for the build status
[12:59:25.339] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:25.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:78:0) after 1m0s
[13:00:25.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:78:0)
[13:00:25.418] Timer tick, asking Buildbucket for the build status
[13:00:25.595] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:25.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:79:0) after 1m0s
[13:01:25.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:79:0)
[13:01:25.684] Timer tick, asking Buildbucket for the build status
[13:01:25.861] Build 8888118968512688992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:25.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053549031160392320:80:0) after 1m0s
[13:02:18.859] Received PubSub notification, asking Buildbucket for the build status
[13:02:18.922] Build 8888118968512688992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:02:18.922] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582030925798803",
"created_by": "project:fuchsia",
"created_ts": "1582026240774390",
"id": "8888118968512688992",
"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\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@560139481239e37a1cc878fb90805fa5e94f623c\", \"title\": \"560139481239e37a1cc878fb90805fa5e94f623c\", \"url\": \"https://gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}}",
"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\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@560139481239e37a1cc878fb90805fa5e94f623c\", \"title\": \"560139481239e37a1cc878fb90805fa5e94f623c\", \"url\": \"https://gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-32-us-central1-f-1-ultz\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-linux\", \"git_revision\": \"560139481239e37a1cc878fb90805fa5e94f623c,685313de6b2ae6e4b2ad32935cbb18d65ebd42ce\", \"instance_id\": \"NA8PedEcFebnnESfNUz-HMeKVi7TAY7TKPd8tbbtENMC\", \"isolated\": \"c894f4c37c06428c33026c16ebdbec17027914fb\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"560139481239e37a1cc878fb90805fa5e94f623c\"}, \"swarming\": {\"bot_dimensions\": {\"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-f-1-ultz\"], \"image\": [\"fuchsia-debian-9-drawfork-v20200123-597b1f5\"], \"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\": [\"4821-6a867f1\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1582026244237435",
"status": "COMPLETED",
"status_changed_ts": "1582030926319530",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/560139481239e37a1cc878fb90805fa5e94f623c",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/560139481239e37a1cc878fb90805fa5e94f623c",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053549031160392320",
"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/8888118968512688992/+/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:4a70ee3e02900010",
"user_agent:luci-scheduler"
],
"updated_ts": "1582030926400102",
"url": "https://ci.chromium.org/b/8888118968512688992",
"utcnow_ts": "1582030938913808"
}
}
[13:02:18.922] Invocation finished in 1h18m20.057331221s with status SUCCEEDED