[06:33:41.279] New invocation is queued and will start shortly
[06:33:42.338] Starting the invocation (attempt 1)
[06:33:42.370] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:33:42.370] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:33:42.370] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9017782751403809888",
"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\":\"3273f9a19e69e2dcd0a92c653a3567464899a29e\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3273f9a19e69e2dcd0a92c653a3567464899a29e\",\"title\":\"3273f9a19e69e2dcd0a92c653a3567464899a29e\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/3273f9a19e69e2dcd0a92c653a3567464899a29e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\",\"title\":\"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"3818d4ab066ee40b976513b247b5da5f20379b66\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3818d4ab066ee40b976513b247b5da5f20379b66\",\"title\":\"3818d4ab066ee40b976513b247b5da5f20379b66\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"3818d4ab066ee40b976513b247b5da5f20379b66\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9017782751403809888",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/3818d4ab066ee40b976513b247b5da5f20379b66",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66",
"gitiles_ref:refs/heads/master"
]
}
[06:33:43.314] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1616135622470132",
"id": "8852352689490949024",
"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\": \"3273f9a19e69e2dcd0a92c653a3567464899a29e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3273f9a19e69e2dcd0a92c653a3567464899a29e\", \"title\": \"3273f9a19e69e2dcd0a92c653a3567464899a29e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/3273f9a19e69e2dcd0a92c653a3567464899a29e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\", \"title\": \"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"3818d4ab066ee40b976513b247b5da5f20379b66\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3818d4ab066ee40b976513b247b5da5f20379b66\", \"title\": \"3818d4ab066ee40b976513b247b5da5f20379b66\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"3818d4ab066ee40b976513b247b5da5f20379b66\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616135623016598",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/3818d4ab066ee40b976513b247b5da5f20379b66",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017782751403809888",
"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/8852352689490949024/+/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": "1616135623016720",
"url": "https://ci.chromium.org/b/8852352689490949024",
"utcnow_ts": "1616135623291491"
}
}
[06:33:43.314] Task URL: https://ci.chromium.org/b/8852352689490949024
[06:33:43.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:2:0) after 1m0s
[06:33:52.650] Received PubSub notification, asking Buildbucket for the build status
[06:33:53.053] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:43.355] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:2:0)
[06:34:43.355] Timer tick, asking Buildbucket for the build status
[06:34:43.455] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:43.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:4:0) after 1m0s
[06:35:43.489] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:4:0)
[06:35:43.489] Timer tick, asking Buildbucket for the build status
[06:35:43.633] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:43.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:5:0) after 1m0s
[06:36:43.652] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:5:0)
[06:36:43.652] Timer tick, asking Buildbucket for the build status
[06:36:43.717] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:43.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:6:0) after 1m0s
[06:37:43.780] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:6:0)
[06:37:43.780] Timer tick, asking Buildbucket for the build status
[06:37:43.904] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:43.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:7:0) after 1m0s
[06:38:43.975] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:7:0)
[06:38:43.975] Timer tick, asking Buildbucket for the build status
[06:38:44.171] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:44.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:8:0) after 1m0s
[06:39:44.227] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:8:0)
[06:39:44.227] Timer tick, asking Buildbucket for the build status
[06:39:44.357] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:44.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:9:0) after 1m0s
[06:40:44.397] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:9:0)
[06:40:44.397] Timer tick, asking Buildbucket for the build status
[06:40:44.459] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:44.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:10:0) after 1m0s
[06:41:44.540] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:10:0)
[06:41:44.540] Timer tick, asking Buildbucket for the build status
[06:41:44.845] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:44.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:11:0) after 1m0s
[06:42:44.913] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:11:0)
[06:42:44.913] Timer tick, asking Buildbucket for the build status
[06:42:44.998] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:44.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:12:0) after 1m0s
[06:43:45.051] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:12:0)
[06:43:45.051] Timer tick, asking Buildbucket for the build status
[06:43:45.403] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:45.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:13:0) after 1m0s
[06:44:45.448] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:13:0)
[06:44:45.448] Timer tick, asking Buildbucket for the build status
[06:44:45.536] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:45.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:14:0) after 1m0s
[06:45:45.563] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:14:0)
[06:45:45.563] Timer tick, asking Buildbucket for the build status
[06:45:45.717] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:45.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:15:0) after 1m0s
[06:46:45.849] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:15:0)
[06:46:45.849] Timer tick, asking Buildbucket for the build status
[06:46:45.915] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:45.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:16:0) after 1m0s
[06:47:45.981] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:16:0)
[06:47:45.981] Timer tick, asking Buildbucket for the build status
[06:47:46.346] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:46.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:17:0) after 1m0s
[06:48:46.418] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:17:0)
[06:48:46.418] Timer tick, asking Buildbucket for the build status
[06:48:46.543] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:46.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:18:0) after 1m0s
[06:49:46.593] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:18:0)
[06:49:46.593] Timer tick, asking Buildbucket for the build status
[06:49:46.660] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:46.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:19:0) after 1m0s
[06:50:46.737] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:19:0)
[06:50:46.737] Timer tick, asking Buildbucket for the build status
[06:50:46.993] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:46.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:20:0) after 1m0s
[06:51:47.054] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:20:0)
[06:51:47.054] Timer tick, asking Buildbucket for the build status
[06:51:47.119] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:47.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:21:0) after 1m0s
[06:52:47.168] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:21:0)
[06:52:47.168] Timer tick, asking Buildbucket for the build status
[06:52:47.240] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:47.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:22:0) after 1m0s
[06:53:47.309] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:22:0)
[06:53:47.309] Timer tick, asking Buildbucket for the build status
[06:53:47.645] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:47.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:23:0) after 1m0s
[06:54:47.728] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:23:0)
[06:54:47.728] Timer tick, asking Buildbucket for the build status
[06:54:47.961] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:47.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:24:0) after 1m0s
[06:55:47.978] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:24:0)
[06:55:47.978] Timer tick, asking Buildbucket for the build status
[06:55:48.159] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:48.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:25:0) after 1m0s
[06:56:48.177] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:25:0)
[06:56:48.177] Timer tick, asking Buildbucket for the build status
[06:56:48.244] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:48.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:26:0) after 1m0s
[06:57:48.259] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:26:0)
[06:57:48.259] Timer tick, asking Buildbucket for the build status
[06:57:48.326] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:48.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:27:0) after 1m0s
[06:58:48.313] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:27:0)
[06:58:48.313] Timer tick, asking Buildbucket for the build status
[06:58:48.603] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:48.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:28:0) after 1m0s
[06:59:48.616] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:28:0)
[06:59:48.616] Timer tick, asking Buildbucket for the build status
[06:59:48.744] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:48.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:29:0) after 1m0s
[07:00:48.787] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:29:0)
[07:00:48.787] Timer tick, asking Buildbucket for the build status
[07:00:48.864] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:48.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:30:0) after 1m0s
[07:01:48.905] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:30:0)
[07:01:48.905] Timer tick, asking Buildbucket for the build status
[07:01:48.971] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:48.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:31:0) after 1m0s
[07:02:48.984] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:31:0)
[07:02:48.984] Timer tick, asking Buildbucket for the build status
[07:02:49.177] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:49.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:32:0) after 1m0s
[07:03:49.194] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:32:0)
[07:03:49.194] Timer tick, asking Buildbucket for the build status
[07:03:49.334] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:49.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:33:0) after 1m0s
[07:04:49.468] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:33:0)
[07:04:49.468] Timer tick, asking Buildbucket for the build status
[07:04:49.752] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:49.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:34:0) after 1m0s
[07:05:49.753] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:34:0)
[07:05:49.753] Timer tick, asking Buildbucket for the build status
[07:05:50.383] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:50.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:35:0) after 1m0s
[07:06:50.448] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:35:0)
[07:06:50.448] Timer tick, asking Buildbucket for the build status
[07:06:50.710] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:50.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:36:0) after 1m0s
[07:07:50.735] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:36:0)
[07:07:50.735] Timer tick, asking Buildbucket for the build status
[07:07:50.817] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:50.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:37:0) after 1m0s
[07:08:50.823] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:37:0)
[07:08:50.823] Timer tick, asking Buildbucket for the build status
[07:08:50.932] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:50.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:38:0) after 1m0s
[07:09:50.996] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:38:0)
[07:09:50.996] Timer tick, asking Buildbucket for the build status
[07:09:51.072] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:51.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:39:0) after 1m0s
[07:10:51.158] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:39:0)
[07:10:51.158] Timer tick, asking Buildbucket for the build status
[07:10:51.251] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:51.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:40:0) after 1m0s
[07:11:51.345] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:40:0)
[07:11:51.345] Timer tick, asking Buildbucket for the build status
[07:11:51.551] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:51.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:41:0) after 1m0s
[07:12:51.568] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:41:0)
[07:12:51.568] Timer tick, asking Buildbucket for the build status
[07:12:51.628] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:51.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:42:0) after 1m0s
[07:13:51.684] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:42:0)
[07:13:51.684] Timer tick, asking Buildbucket for the build status
[07:13:51.946] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:51.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:43:0) after 1m0s
[07:14:52.036] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:43:0)
[07:14:52.036] Timer tick, asking Buildbucket for the build status
[07:14:52.259] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:52.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:44:0) after 1m0s
[07:15:52.463] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:44:0)
[07:15:52.463] Timer tick, asking Buildbucket for the build status
[07:15:52.550] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:52.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:45:0) after 1m0s
[07:16:52.635] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:45:0)
[07:16:52.635] Timer tick, asking Buildbucket for the build status
[07:16:52.706] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:52.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:46:0) after 1m0s
[07:17:52.725] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:46:0)
[07:17:52.726] Timer tick, asking Buildbucket for the build status
[07:17:52.810] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:52.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:47:0) after 1m0s
[07:18:52.874] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:47:0)
[07:18:52.874] Timer tick, asking Buildbucket for the build status
[07:18:53.051] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:53.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:48:0) after 1m0s
[07:19:53.122] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:48:0)
[07:19:53.122] Timer tick, asking Buildbucket for the build status
[07:19:53.951] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:53.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:49:0) after 1m0s
[07:20:53.949] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:49:0)
[07:20:53.949] Timer tick, asking Buildbucket for the build status
[07:20:54.359] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:54.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:50:0) after 1m0s
[07:21:54.376] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:50:0)
[07:21:54.376] Timer tick, asking Buildbucket for the build status
[07:21:54.750] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:54.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:51:0) after 1m0s
[07:22:54.788] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:51:0)
[07:22:54.788] Timer tick, asking Buildbucket for the build status
[07:22:55.121] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:55.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:52:0) after 1m0s
[07:23:55.170] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:52:0)
[07:23:55.170] Timer tick, asking Buildbucket for the build status
[07:23:55.251] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:53:0) after 1m0s
[07:24:55.271] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:53:0)
[07:24:55.271] Timer tick, asking Buildbucket for the build status
[07:24:55.368] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:55.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:54:0) after 1m0s
[07:25:55.496] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:54:0)
[07:25:55.496] Timer tick, asking Buildbucket for the build status
[07:25:55.581] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:55.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:55:0) after 1m0s
[07:26:55.675] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:55:0)
[07:26:55.675] Timer tick, asking Buildbucket for the build status
[07:26:55.834] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:55.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:56:0) after 1m0s
[07:27:55.840] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:56:0)
[07:27:55.840] Timer tick, asking Buildbucket for the build status
[07:27:55.966] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:55.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:57:0) after 1m0s
[07:28:56.011] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:57:0)
[07:28:56.011] Timer tick, asking Buildbucket for the build status
[07:28:56.440] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:56.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:58:0) after 1m0s
[07:29:56.420] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:58:0)
[07:29:56.420] Timer tick, asking Buildbucket for the build status
[07:29:56.536] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:56.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:59:0) after 1m0s
[07:30:56.551] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:59:0)
[07:30:56.551] Timer tick, asking Buildbucket for the build status
[07:30:56.637] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:56.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:60:0) after 1m0s
[07:31:56.668] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:60:0)
[07:31:56.668] Timer tick, asking Buildbucket for the build status
[07:31:56.736] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:56.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:61:0) after 1m0s
[07:32:56.803] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:61:0)
[07:32:56.803] Timer tick, asking Buildbucket for the build status
[07:32:56.868] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:56.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:62:0) after 1m0s
[07:33:56.891] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:62:0)
[07:33:56.891] Timer tick, asking Buildbucket for the build status
[07:33:56.971] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:56.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:63:0) after 1m0s
[07:34:56.989] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:63:0)
[07:34:56.989] Timer tick, asking Buildbucket for the build status
[07:34:57.221] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:57.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:64:0) after 1m0s
[07:35:57.284] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:64:0)
[07:35:57.284] Timer tick, asking Buildbucket for the build status
[07:35:57.395] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:57.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:65:0) after 1m0s
[07:36:57.423] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:65:0)
[07:36:57.423] Timer tick, asking Buildbucket for the build status
[07:36:57.502] Build 8852352689490949024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:57.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9017782751403809888:66:0) after 1m0s
[07:37:16.130] Received PubSub notification, asking Buildbucket for the build status
[07:37:16.201] Build 8852352689490949024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:37:16.201] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1616139435849543",
"created_by": "project:fuchsia",
"created_ts": "1616135622470132",
"id": "8852352689490949024",
"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\": \"3273f9a19e69e2dcd0a92c653a3567464899a29e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3273f9a19e69e2dcd0a92c653a3567464899a29e\", \"title\": \"3273f9a19e69e2dcd0a92c653a3567464899a29e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/3273f9a19e69e2dcd0a92c653a3567464899a29e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\", \"title\": \"51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/51f6e7a9f4210aed0f8156c1d2e348de6f96f37d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"3818d4ab066ee40b976513b247b5da5f20379b66\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3818d4ab066ee40b976513b247b5da5f20379b66\", \"title\": \"3818d4ab066ee40b976513b247b5da5f20379b66\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"3818d4ab066ee40b976513b247b5da5f20379b66\"}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder\", \"git\", \"goma_v2\", \"symbol\", \"vpython\"], \"cores\": [\"2\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-e2-2-ssd0-us-central1-b-0-duod\"], \"image\": [\"fuchsia-debian-9-v20210121-f1877f1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-2\"], \"os\": [\"Debian\", \"Debian-9\", \"Debian-9.13\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1616135631448964",
"status": "COMPLETED",
"status_changed_ts": "1616135632308075",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/3818d4ab066ee40b976513b247b5da5f20379b66",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/3818d4ab066ee40b976513b247b5da5f20379b66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017782751403809888",
"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/8852352689490949024/+/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:5262021c8fb66510",
"user_agent:luci-scheduler"
],
"updated_ts": "1616139435849901",
"url": "https://ci.chromium.org/b/8852352689490949024",
"utcnow_ts": "1616139436194098"
}
}
[07:37:16.201] Invocation finished in 1h3m34.938870122s with status SUCCEEDED