[21:44:13.657] New invocation is queued and will start shortly
[21:44:16.820] Starting the invocation (attempt 1)
[21:44:16.871] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:44:16.871] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:44:16.871] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9053330072999847296",
"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\":\"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"},\"id\":\"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\",\"title\":\"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\",\"url\":\"https://gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}]},\"branch\":\"refs/heads/releases/gcc-9\",\"repository\":\"https://gnu.googlesource.com/gcc\",\"revision\":\"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-x64-linux",
"scheduler_invocation_id:9053330072999847296",
"scheduler_job_id:fuchsia/prod-gcc-x64-linux",
"user_agent:luci-scheduler",
"buildset:commit/git/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"gitiles_ref:refs/heads/releases/gcc-9"
]
}
[21:44:17.773] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1582235056975343",
"id": "8887900008856060928",
"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\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"title\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"url\": \"https://gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582235057492503",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053330072999847296",
"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/8887900008856060928/+/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": "1582235057492634",
"url": "https://ci.chromium.org/b/8887900008856060928",
"utcnow_ts": "1582235057763844"
}
}
[21:44:17.773] Task URL: https://ci.chromium.org/b/8887900008856060928
[21:44:17.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:2:0) after 1m0s
[21:44:27.267] Received PubSub notification, asking Buildbucket for the build status
[21:44:27.372] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:18.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:2:0)
[21:45:18.109] Timer tick, asking Buildbucket for the build status
[21:45:18.253] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:18.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:4:0) after 1m0s
[21:46:18.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:4:0)
[21:46:18.470] Timer tick, asking Buildbucket for the build status
[21:46:18.745] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:18.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:5:0) after 1m0s
[21:47:18.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:5:0)
[21:47:18.816] Timer tick, asking Buildbucket for the build status
[21:47:19.045] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:19.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:6:0) after 1m0s
[21:48:19.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:6:0)
[21:48:19.137] Timer tick, asking Buildbucket for the build status
[21:48:19.657] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:19.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:7:0) after 1m0s
[21:49:19.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:7:0)
[21:49:19.857] Timer tick, asking Buildbucket for the build status
[21:49:20.157] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:20.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:8:0) after 1m0s
[21:50:20.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:8:0)
[21:50:20.214] Timer tick, asking Buildbucket for the build status
[21:50:20.340] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:20.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:9:0) after 1m0s
[21:51:20.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:9:0)
[21:51:20.775] Timer tick, asking Buildbucket for the build status
[21:51:20.915] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:20.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:10:0) after 1m0s
[21:52:20.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:10:0)
[21:52:20.996] Timer tick, asking Buildbucket for the build status
[21:52:21.287] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:21.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:11:0) after 1m0s
[21:53:21.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:11:0)
[21:53:21.283] Timer tick, asking Buildbucket for the build status
[21:53:21.524] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:21.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:12:0) after 1m0s
[21:54:21.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:12:0)
[21:54:21.586] Timer tick, asking Buildbucket for the build status
[21:54:21.718] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:21.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:13:0) after 1m0s
[21:55:21.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:13:0)
[21:55:21.942] Timer tick, asking Buildbucket for the build status
[21:55:22.302] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:22.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:14:0) after 1m0s
[21:56:22.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:14:0)
[21:56:22.330] Timer tick, asking Buildbucket for the build status
[21:56:22.457] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:22.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:15:0) after 1m0s
[21:57:22.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:15:0)
[21:57:22.539] Timer tick, asking Buildbucket for the build status
[21:57:22.676] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:22.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:16:0) after 1m0s
[21:58:22.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:16:0)
[21:58:22.749] Timer tick, asking Buildbucket for the build status
[21:58:22.923] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:22.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:17:0) after 1m0s
[21:59:23.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:17:0)
[21:59:23.024] Timer tick, asking Buildbucket for the build status
[21:59:23.205] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:23.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:18:0) after 1m0s
[22:00:23.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:18:0)
[22:00:23.301] Timer tick, asking Buildbucket for the build status
[22:00:23.412] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:23.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:19:0) after 1m0s
[22:01:23.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:19:0)
[22:01:23.496] Timer tick, asking Buildbucket for the build status
[22:01:23.580] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:23.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:20:0) after 1m0s
[22:02:23.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:20:0)
[22:02:23.630] Timer tick, asking Buildbucket for the build status
[22:02:23.764] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:23.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:21:0) after 1m0s
[22:03:23.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:21:0)
[22:03:23.796] Timer tick, asking Buildbucket for the build status
[22:03:24.303] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:24.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:22:0) after 1m0s
[22:04:24.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:22:0)
[22:04:24.408] Timer tick, asking Buildbucket for the build status
[22:04:24.964] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:24.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:23:0) after 1m0s
[22:05:25.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:23:0)
[22:05:25.116] Timer tick, asking Buildbucket for the build status
[22:05:25.688] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:25.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:24:0) after 1m0s
[22:06:25.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:24:0)
[22:06:25.706] Timer tick, asking Buildbucket for the build status
[22:06:25.928] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:25.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:25:0) after 1m0s
[22:07:26.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:25:0)
[22:07:26.023] Timer tick, asking Buildbucket for the build status
[22:07:26.159] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:26.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:26:0) after 1m0s
[22:08:26.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:26:0)
[22:08:26.212] Timer tick, asking Buildbucket for the build status
[22:08:26.637] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:26.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:27:0) after 1m0s
[22:09:26.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:27:0)
[22:09:26.696] Timer tick, asking Buildbucket for the build status
[22:09:27.657] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:27.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:28:0) after 1m0s
[22:10:27.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:28:0)
[22:10:27.688] Timer tick, asking Buildbucket for the build status
[22:10:27.915] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:27.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:29:0) after 1m0s
[22:11:28.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:29:0)
[22:11:28.031] Timer tick, asking Buildbucket for the build status
[22:11:29.271] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:29.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:30:0) after 1m0s
[22:12:29.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:30:0)
[22:12:29.436] Timer tick, asking Buildbucket for the build status
[22:12:29.597] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:29.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:31:0) after 1m0s
[22:13:29.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:31:0)
[22:13:29.621] Timer tick, asking Buildbucket for the build status
[22:13:29.731] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:29.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:32:0) after 1m0s
[22:14:29.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:32:0)
[22:14:29.760] Timer tick, asking Buildbucket for the build status
[22:14:30.007] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:30.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:33:0) after 1m0s
[22:15:30.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:33:0)
[22:15:30.047] Timer tick, asking Buildbucket for the build status
[22:15:31.842] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:31.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:34:0) after 1m0s
[22:16:32.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:34:0)
[22:16:32.049] Timer tick, asking Buildbucket for the build status
[22:16:32.143] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:32.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:35:0) after 1m0s
[22:17:32.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:35:0)
[22:17:32.252] Timer tick, asking Buildbucket for the build status
[22:17:32.659] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:32.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:36:0) after 1m0s
[22:18:32.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:36:0)
[22:18:32.754] Timer tick, asking Buildbucket for the build status
[22:18:32.884] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:32.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:37:0) after 1m0s
[22:19:32.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:37:0)
[22:19:32.938] Timer tick, asking Buildbucket for the build status
[22:19:33.065] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:33.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:38:0) after 1m0s
[22:20:33.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:38:0)
[22:20:33.188] Timer tick, asking Buildbucket for the build status
[22:20:34.887] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:34.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:39:0) after 1m0s
[22:21:35.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:39:0)
[22:21:35.047] Timer tick, asking Buildbucket for the build status
[22:21:35.316] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:35.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:40:0) after 1m0s
[22:22:35.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:40:0)
[22:22:35.415] Timer tick, asking Buildbucket for the build status
[22:22:36.091] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:36.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:41:0) after 1m0s
[22:23:36.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:41:0)
[22:23:36.113] Timer tick, asking Buildbucket for the build status
[22:23:36.326] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:36.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:42:0) after 1m0s
[22:24:36.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:42:0)
[22:24:36.432] Timer tick, asking Buildbucket for the build status
[22:24:36.547] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:36.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:43:0) after 1m0s
[22:25:36.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:43:0)
[22:25:36.615] Timer tick, asking Buildbucket for the build status
[22:25:37.929] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:37.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:44:0) after 1m0s
[22:26:38.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:44:0)
[22:26:38.009] Timer tick, asking Buildbucket for the build status
[22:26:38.337] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:38.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:45:0) after 1m0s
[22:27:38.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:45:0)
[22:27:38.351] Timer tick, asking Buildbucket for the build status
[22:27:38.532] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:38.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:46:0) after 1m0s
[22:28:38.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:46:0)
[22:28:38.782] Timer tick, asking Buildbucket for the build status
[22:28:39.012] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:39.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:47:0) after 1m0s
[22:29:39.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:47:0)
[22:29:39.110] Timer tick, asking Buildbucket for the build status
[22:29:39.518] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:39.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:48:0) after 1m0s
[22:30:39.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:48:0)
[22:30:39.540] Timer tick, asking Buildbucket for the build status
[22:30:41.032] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:41.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:49:0) after 1m0s
[22:31:41.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:49:0)
[22:31:41.077] Timer tick, asking Buildbucket for the build status
[22:31:41.230] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:41.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:50:0) after 1m0s
[22:32:41.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:50:0)
[22:32:41.490] Timer tick, asking Buildbucket for the build status
[22:32:42.335] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:51:0) after 1m0s
[22:33:42.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:51:0)
[22:33:42.524] Timer tick, asking Buildbucket for the build status
[22:33:42.719] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:42.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:52:0) after 1m0s
[22:34:42.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:52:0)
[22:34:42.990] Timer tick, asking Buildbucket for the build status
[22:34:43.133] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:53:0) after 1m0s
[22:35:43.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:53:0)
[22:35:43.325] Timer tick, asking Buildbucket for the build status
[22:35:45.180] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:45.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:54:0) after 1m0s
[22:36:45.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:54:0)
[22:36:45.361] Timer tick, asking Buildbucket for the build status
[22:36:45.522] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:45.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:55:0) after 1m0s
[22:37:45.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:55:0)
[22:37:45.613] Timer tick, asking Buildbucket for the build status
[22:37:45.751] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:45.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:56:0) after 1m0s
[22:38:45.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:56:0)
[22:38:45.819] Timer tick, asking Buildbucket for the build status
[22:38:45.986] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:45.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:57:0) after 1m0s
[22:39:46.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:57:0)
[22:39:46.071] Timer tick, asking Buildbucket for the build status
[22:39:46.330] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:46.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:58:0) after 1m0s
[22:40:46.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:58:0)
[22:40:46.448] Timer tick, asking Buildbucket for the build status
[22:40:46.718] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:46.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:59:0) after 1m0s
[22:41:46.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:59:0)
[22:41:46.756] Timer tick, asking Buildbucket for the build status
[22:41:47.080] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:47.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:60:0) after 1m0s
[22:42:47.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:60:0)
[22:42:47.121] Timer tick, asking Buildbucket for the build status
[22:42:47.212] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:47.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:61:0) after 1m0s
[22:43:47.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:61:0)
[22:43:47.326] Timer tick, asking Buildbucket for the build status
[22:43:47.838] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:47.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:62:0) after 1m0s
[22:44:47.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:62:0)
[22:44:47.903] Timer tick, asking Buildbucket for the build status
[22:44:48.017] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:48.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:63:0) after 1m0s
[22:45:48.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:63:0)
[22:45:48.044] Timer tick, asking Buildbucket for the build status
[22:45:49.635] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:49.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:64:0) after 1m0s
[22:46:49.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:64:0)
[22:46:49.665] Timer tick, asking Buildbucket for the build status
[22:46:50.662] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:50.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:65:0) after 1m0s
[22:47:50.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:65:0)
[22:47:50.733] Timer tick, asking Buildbucket for the build status
[22:47:50.908] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:50.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:66:0) after 1m0s
[22:48:50.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:66:0)
[22:48:50.912] Timer tick, asking Buildbucket for the build status
[22:48:51.274] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:51.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:67:0) after 1m0s
[22:49:51.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:67:0)
[22:49:51.323] Timer tick, asking Buildbucket for the build status
[22:49:51.548] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:51.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:68:0) after 1m0s
[22:50:51.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:68:0)
[22:50:51.610] Timer tick, asking Buildbucket for the build status
[22:50:52.412] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:52.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:69:0) after 1m0s
[22:51:52.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:69:0)
[22:51:52.455] Timer tick, asking Buildbucket for the build status
[22:51:52.678] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:52.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:70:0) after 1m0s
[22:52:52.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:70:0)
[22:52:52.744] Timer tick, asking Buildbucket for the build status
[22:52:52.965] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:52.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:71:0) after 1m0s
[22:53:53.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:71:0)
[22:53:53.022] Timer tick, asking Buildbucket for the build status
[22:53:53.169] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:53.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:72:0) after 1m0s
[22:54:53.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:72:0)
[22:54:53.196] Timer tick, asking Buildbucket for the build status
[22:54:53.327] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:53.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:73:0) after 1m0s
[22:55:53.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:73:0)
[22:55:53.324] Timer tick, asking Buildbucket for the build status
[22:55:54.371] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:54.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:74:0) after 1m0s
[22:56:54.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:74:0)
[22:56:54.352] Timer tick, asking Buildbucket for the build status
[22:56:54.472] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:54.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:75:0) after 1m0s
[22:57:54.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:75:0)
[22:57:54.499] Timer tick, asking Buildbucket for the build status
[22:57:54.659] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:76:0) after 1m0s
[22:58:54.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:76:0)
[22:58:54.679] Timer tick, asking Buildbucket for the build status
[22:58:54.941] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:54.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:77:0) after 1m0s
[22:59:55.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:77:0)
[22:59:55.021] Timer tick, asking Buildbucket for the build status
[22:59:56.320] Build 8887900008856060928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:56.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-x64-linux:9053330072999847296:78:0) after 1m0s
[23:00:24.960] Received PubSub notification, asking Buildbucket for the build status
[23:00:28.700] Build 8887900008856060928: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:00:28.700] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1582239624243099",
"created_by": "project:fuchsia",
"created_ts": "1582235056975343",
"id": "8887900008856060928",
"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\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"title\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"url\": \"https://gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}]}, \"branch\": \"refs/heads/releases/gcc-9\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}}",
"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\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}, \"id\": \"https://gnu.googlesource.com/gcc/+/refs/heads/releases/gcc-9@bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"title\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\", \"url\": \"https://gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-32-us-central1-f-1-54mt\", \"branch\": \"refs/heads/releases/gcc-9\", \"buildername\": \"gcc-x64-linux\", \"git_revision\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0,a9c798035de33ccc3bc3e494449bbe931e900372\", \"instance_id\": \"Dro9Wn1v5Qwevj4IUe_TmCNvM0mCpjudYPFwYKrveyQC\", \"isolated\": \"5d507499ddecfc498f50693b52d6990692b846e7\", \"mastername\": \"gcc\", \"package\": \"fuchsia/third_party/gcc/linux-amd64\", \"path_config\": \"generic\", \"recipe\": \"gcc_toolchain\", \"repository\": \"https://gnu.googlesource.com/gcc\", \"revision\": \"bd2537ed5d4cda1a896974f30bd62dfb68ae39b0\"}, \"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-54mt\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1582235063607971",
"status": "COMPLETED",
"status_changed_ts": "1582239624608004",
"tags": [
"builder:gcc-x64-linux",
"buildset:commit/git/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/bd2537ed5d4cda1a896974f30bd62dfb68ae39b0",
"gitiles_ref:refs/heads/releases/gcc-9",
"scheduler_invocation_id:9053330072999847296",
"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/8887900008856060928/+/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:4a7d60898b2ba510",
"user_agent:luci-scheduler"
],
"updated_ts": "1582239624616180",
"url": "https://ci.chromium.org/b/8887900008856060928",
"utcnow_ts": "1582239628692191"
}
}
[23:00:28.700] Invocation finished in 1h16m15.060652514s with status SUCCEEDED