[06:33:51.532] New invocation is queued and will start shortly
[06:33:52.854] Starting the invocation (attempt 1)
[06:33:52.903] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:33:52.906] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:33:52.906] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9043602876078195232",
"parameters_json": "{\"builder_name\":\"gcc-prod-trigger\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@bcab203d31b2dd7e0b35abda34c42c278217bcf6\",\"title\":\"bcab203d31b2dd7e0b35abda34c42c278217bcf6\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9043602876078195232",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"gitiles_ref:refs/heads/master"
]
}
[06:33:57.521] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1591511633135538",
"id": "8878172813732482064",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"title\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591511636544573",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043602876078195232",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8878172813732482064/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1591511636544775",
"url": "https://ci.chromium.org/b/8878172813732482064",
"utcnow_ts": "1591511637471815"
}
}
[06:33:57.521] Task URL: https://ci.chromium.org/b/8878172813732482064
[06:33:57.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:2:0) after 1m0s
[06:34:38.612] Received PubSub notification, asking Buildbucket for the build status
[06:34:38.723] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:57.546] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:2:0)
[06:34:57.546] Timer tick, asking Buildbucket for the build status
[06:34:58.158] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:58.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:4:0) after 1m0s
[06:35:58.181] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:4:0)
[06:35:58.181] Timer tick, asking Buildbucket for the build status
[06:35:58.760] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:58.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:5:0) after 1m0s
[06:36:58.787] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:5:0)
[06:36:58.787] Timer tick, asking Buildbucket for the build status
[06:36:59.044] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:59.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:6:0) after 1m0s
[06:37:59.074] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:6:0)
[06:37:59.074] Timer tick, asking Buildbucket for the build status
[06:37:59.171] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:59.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:7:0) after 1m0s
[06:38:59.189] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:7:0)
[06:38:59.189] Timer tick, asking Buildbucket for the build status
[06:38:59.836] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:59.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:8:0) after 1m0s
[06:39:59.859] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:8:0)
[06:39:59.859] Timer tick, asking Buildbucket for the build status
[06:40:00.190] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:00.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:9:0) after 1m0s
[06:41:00.213] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:9:0)
[06:41:00.214] Timer tick, asking Buildbucket for the build status
[06:41:00.812] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:00.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:10:0) after 1m0s
[06:42:00.964] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:10:0)
[06:42:00.964] Timer tick, asking Buildbucket for the build status
[06:42:01.102] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:01.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:11:0) after 1m0s
[06:43:01.726] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:11:0)
[06:43:01.726] Timer tick, asking Buildbucket for the build status
[06:43:01.866] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:01.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:12:0) after 1m0s
[06:44:01.889] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:12:0)
[06:44:01.889] Timer tick, asking Buildbucket for the build status
[06:44:02.205] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:02.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:13:0) after 1m0s
[06:45:02.564] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:13:0)
[06:45:02.564] Timer tick, asking Buildbucket for the build status
[06:45:03.336] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:03.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:14:0) after 1m0s
[06:46:03.421] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:14:0)
[06:46:03.421] Timer tick, asking Buildbucket for the build status
[06:46:03.550] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:03.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:15:0) after 1m0s
[06:47:03.575] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:15:0)
[06:47:03.575] Timer tick, asking Buildbucket for the build status
[06:47:03.703] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:03.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:16:0) after 1m0s
[06:48:03.882] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:16:0)
[06:48:03.882] Timer tick, asking Buildbucket for the build status
[06:48:04.351] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:04.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:17:0) after 1m0s
[06:49:04.376] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:17:0)
[06:49:04.376] Timer tick, asking Buildbucket for the build status
[06:49:04.550] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:04.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:18:0) after 1m0s
[06:50:04.711] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:18:0)
[06:50:04.711] Timer tick, asking Buildbucket for the build status
[06:50:04.959] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:04.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:19:0) after 1m0s
[06:51:05.404] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:19:0)
[06:51:05.404] Timer tick, asking Buildbucket for the build status
[06:51:05.639] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:05.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:20:0) after 1m0s
[06:52:05.664] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:20:0)
[06:52:05.664] Timer tick, asking Buildbucket for the build status
[06:52:07.200] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:07.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:21:0) after 1m0s
[06:53:07.453] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:21:0)
[06:53:07.453] Timer tick, asking Buildbucket for the build status
[06:53:07.831] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:07.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:22:0) after 1m0s
[06:54:07.853] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:22:0)
[06:54:07.853] Timer tick, asking Buildbucket for the build status
[06:54:08.204] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:23:0) after 1m0s
[06:55:08.231] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:23:0)
[06:55:08.231] Timer tick, asking Buildbucket for the build status
[06:55:08.428] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:08.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:24:0) after 1m0s
[06:56:08.484] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:24:0)
[06:56:08.484] Timer tick, asking Buildbucket for the build status
[06:56:09.154] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:09.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:25:0) after 1m0s
[06:57:09.213] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:25:0)
[06:57:09.213] Timer tick, asking Buildbucket for the build status
[06:57:09.379] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:09.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:26:0) after 1m0s
[06:58:09.556] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:26:0)
[06:58:09.556] Timer tick, asking Buildbucket for the build status
[06:58:09.823] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:09.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:27:0) after 1m0s
[06:59:09.849] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:27:0)
[06:59:09.849] Timer tick, asking Buildbucket for the build status
[06:59:10.015] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:10.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:28:0) after 1m0s
[07:00:10.037] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:28:0)
[07:00:10.037] Timer tick, asking Buildbucket for the build status
[07:00:10.306] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:10.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:29:0) after 1m0s
[07:01:12.528] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:29:0)
[07:01:12.528] Timer tick, asking Buildbucket for the build status
[07:01:12.717] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:12.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:30:0) after 1m0s
[07:02:18.332] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:30:0)
[07:02:18.332] Timer tick, asking Buildbucket for the build status
[07:02:18.573] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:18.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:31:0) after 1m0s
[07:03:18.604] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:31:0)
[07:03:18.604] Timer tick, asking Buildbucket for the build status
[07:03:18.820] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:18.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:32:0) after 1m0s
[07:04:18.838] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:32:0)
[07:04:18.838] Timer tick, asking Buildbucket for the build status
[07:04:18.987] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:18.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:33:0) after 1m0s
[07:05:18.974] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:33:0)
[07:05:18.974] Timer tick, asking Buildbucket for the build status
[07:05:20.700] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:20.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:34:0) after 1m0s
[07:06:21.684] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:34:0)
[07:06:21.684] Timer tick, asking Buildbucket for the build status
[07:06:22.918] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:22.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:35:0) after 1m0s
[07:07:22.989] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:35:0)
[07:07:22.989] Timer tick, asking Buildbucket for the build status
[07:07:23.529] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:23.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:36:0) after 1m0s
[07:08:23.649] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:36:0)
[07:08:23.649] Timer tick, asking Buildbucket for the build status
[07:08:23.803] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:23.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:37:0) after 1m0s
[07:09:23.976] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:37:0)
[07:09:23.976] Timer tick, asking Buildbucket for the build status
[07:09:25.608] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:25.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:38:0) after 1m0s
[07:10:25.691] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:38:0)
[07:10:25.691] Timer tick, asking Buildbucket for the build status
[07:10:25.935] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:25.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:39:0) after 1m0s
[07:11:26.110] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:39:0)
[07:11:26.110] Timer tick, asking Buildbucket for the build status
[07:11:26.319] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:26.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:40:0) after 1m0s
[07:12:26.385] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:40:0)
[07:12:26.385] Timer tick, asking Buildbucket for the build status
[07:12:26.636] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:26.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:41:0) after 1m0s
[07:13:26.731] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:41:0)
[07:13:26.731] Timer tick, asking Buildbucket for the build status
[07:13:27.368] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:27.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:42:0) after 1m0s
[07:14:27.393] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:42:0)
[07:14:27.393] Timer tick, asking Buildbucket for the build status
[07:14:27.759] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:27.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:43:0) after 1m0s
[07:15:27.790] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:43:0)
[07:15:27.790] Timer tick, asking Buildbucket for the build status
[07:15:27.951] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:27.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:44:0) after 1m0s
[07:16:28.035] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:44:0)
[07:16:28.035] Timer tick, asking Buildbucket for the build status
[07:16:30.492] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:30.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:45:0) after 1m0s
[07:17:30.565] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:45:0)
[07:17:30.565] Timer tick, asking Buildbucket for the build status
[07:17:30.873] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:30.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:46:0) after 1m0s
[07:18:30.879] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:46:0)
[07:18:30.879] Timer tick, asking Buildbucket for the build status
[07:18:31.403] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:31.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:47:0) after 1m0s
[07:19:31.433] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:47:0)
[07:19:31.433] Timer tick, asking Buildbucket for the build status
[07:19:31.581] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:31.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:48:0) after 1m0s
[07:20:31.610] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:48:0)
[07:20:31.610] Timer tick, asking Buildbucket for the build status
[07:20:31.767] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:31.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:49:0) after 1m0s
[07:21:31.850] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:49:0)
[07:21:31.850] Timer tick, asking Buildbucket for the build status
[07:21:32.874] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:32.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:50:0) after 1m0s
[07:22:33.031] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:50:0)
[07:22:33.031] Timer tick, asking Buildbucket for the build status
[07:22:33.594] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:33.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:51:0) after 1m0s
[07:23:33.624] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:51:0)
[07:23:33.624] Timer tick, asking Buildbucket for the build status
[07:23:34.658] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:34.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:52:0) after 1m0s
[07:24:34.680] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:52:0)
[07:24:34.680] Timer tick, asking Buildbucket for the build status
[07:24:36.009] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:36.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:53:0) after 1m0s
[07:25:36.104] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:53:0)
[07:25:36.104] Timer tick, asking Buildbucket for the build status
[07:25:37.110] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:37.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:54:0) after 1m0s
[07:26:37.132] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:54:0)
[07:26:37.132] Timer tick, asking Buildbucket for the build status
[07:26:38.234] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:38.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:55:0) after 1m0s
[07:27:38.262] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:55:0)
[07:27:38.262] Timer tick, asking Buildbucket for the build status
[07:27:39.118] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:39.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:56:0) after 1m0s
[07:28:39.187] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:56:0)
[07:28:39.187] Timer tick, asking Buildbucket for the build status
[07:28:39.365] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:39.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:57:0) after 1m0s
[07:29:39.424] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:57:0)
[07:29:39.424] Timer tick, asking Buildbucket for the build status
[07:29:40.272] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:40.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:58:0) after 1m0s
[07:30:40.295] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:58:0)
[07:30:40.295] Timer tick, asking Buildbucket for the build status
[07:30:40.471] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:40.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:59:0) after 1m0s
[07:31:40.686] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:59:0)
[07:31:40.686] Timer tick, asking Buildbucket for the build status
[07:31:41.226] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:41.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:60:0) after 1m0s
[07:32:41.261] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:60:0)
[07:32:41.261] Timer tick, asking Buildbucket for the build status
[07:32:41.788] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:41.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:61:0) after 1m0s
[07:33:41.914] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:61:0)
[07:33:41.914] Timer tick, asking Buildbucket for the build status
[07:33:42.159] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:42.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:62:0) after 1m0s
[07:34:42.194] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:62:0)
[07:34:42.194] Timer tick, asking Buildbucket for the build status
[07:34:42.468] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:42.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:63:0) after 1m0s
[07:35:42.495] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:63:0)
[07:35:42.495] Timer tick, asking Buildbucket for the build status
[07:35:42.873] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:42.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:64:0) after 1m0s
[07:36:42.911] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:64:0)
[07:36:42.911] Timer tick, asking Buildbucket for the build status
[07:36:44.172] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:44.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:65:0) after 1m0s
[07:37:44.185] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:65:0)
[07:37:44.185] Timer tick, asking Buildbucket for the build status
[07:37:44.374] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:44.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:66:0) after 1m0s
[07:38:44.401] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:66:0)
[07:38:44.401] Timer tick, asking Buildbucket for the build status
[07:38:44.748] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:44.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:67:0) after 1m0s
[07:39:44.784] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:67:0)
[07:39:44.784] Timer tick, asking Buildbucket for the build status
[07:39:45.245] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:45.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:68:0) after 1m0s
[07:40:45.287] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:68:0)
[07:40:45.287] Timer tick, asking Buildbucket for the build status
[07:40:45.843] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:45.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:69:0) after 1m0s
[07:41:45.988] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:69:0)
[07:41:45.988] Timer tick, asking Buildbucket for the build status
[07:41:47.168] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:47.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:70:0) after 1m0s
[07:42:47.238] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:70:0)
[07:42:47.238] Timer tick, asking Buildbucket for the build status
[07:42:47.773] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:47.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:71:0) after 1m0s
[07:43:47.810] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:71:0)
[07:43:47.811] Timer tick, asking Buildbucket for the build status
[07:43:48.122] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:48.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:72:0) after 1m0s
[07:44:48.152] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:72:0)
[07:44:48.152] Timer tick, asking Buildbucket for the build status
[07:44:48.498] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:48.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:73:0) after 1m0s
[07:45:48.543] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:73:0)
[07:45:48.543] Timer tick, asking Buildbucket for the build status
[07:45:48.735] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:48.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:74:0) after 1m0s
[07:46:48.865] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:74:0)
[07:46:48.865] Timer tick, asking Buildbucket for the build status
[07:46:49.053] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:49.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:75:0) after 1m0s
[07:47:49.090] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:75:0)
[07:47:49.090] Timer tick, asking Buildbucket for the build status
[07:47:49.655] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:49.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:76:0) after 1m0s
[07:48:49.717] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:76:0)
[07:48:49.717] Timer tick, asking Buildbucket for the build status
[07:48:49.895] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:49.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:77:0) after 1m0s
[07:49:49.868] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:77:0)
[07:49:49.868] Timer tick, asking Buildbucket for the build status
[07:49:50.161] Build 8878172813732482064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:50.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9043602876078195232:78:0) after 1m0s
[07:50:17.748] Received PubSub notification, asking Buildbucket for the build status
[07:50:18.348] Build 8878172813732482064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:50:18.348] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1591516215518585",
"created_by": "project:fuchsia",
"created_ts": "1591511633135538",
"id": "8878172813732482064",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"title\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}}",
"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/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"title\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-n1-1-ssd0-us-central1-a-1-4rkp\", \"branch\": \"refs/heads/master\", \"bucket\": \"prod\", \"buildername\": \"gcc-prod-trigger\", \"builders\": [\"gcc-arm64-linux\", \"gcc-x64-linux\", \"gcc-x64-mac\"], \"enforce_output\": true, \"mastername\": \"gcc\", \"path_config\": \"generic\", \"recipe\": \"toolchain_trigger\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"bcab203d31b2dd7e0b35abda34c42c278217bcf6\", \"timeout\": 7200.0, \"triggers\": [\"gcc-goma\"]}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-n1-1-ssd0-us-central1-a-1-4rkp\"], \"image\": [\"fuchsia-debian-9-drawfork-v20200528-ce1d0cc\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.11\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"scale_group\": [\"fuchsia-prod-n1-1-ssd0\"], \"server_version\": [\"5168-d212e19\"], \"ssd\": [\"1\"], \"zone\": [\"cloud\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1591511672303474",
"status": "COMPLETED",
"status_changed_ts": "1591516217104066",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/bcab203d31b2dd7e0b35abda34c42c278217bcf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043602876078195232",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8878172813732482064/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4ca64de4c2fb1310",
"user_agent:luci-scheduler"
],
"updated_ts": "1591516217116711",
"url": "https://ci.chromium.org/b/8878172813732482064",
"utcnow_ts": "1591516218321965"
}
}
[07:50:18.348] Invocation finished in 1h16m26.833845656s with status SUCCEEDED