[18:33:57.804] New invocation is queued and will start shortly
[18:33:59.123] Starting the invocation (attempt 1)
[18:33:59.156] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:59.156] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:59.157] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9020727135483795264",
"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\":\"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5b1f6c95704da7128ca2a7c682a2e0ef533180ce\",\"title\":\"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@0d0a0d86c841a95226d9cc7c247c770c38fdc81b\",\"title\":\"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"1944212b426d2e13cc4bb24aa1a850bbc572e624\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1944212b426d2e13cc4bb24aa1a850bbc572e624\",\"title\":\"1944212b426d2e13cc4bb24aa1a850bbc572e624\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/1944212b426d2e13cc4bb24aa1a850bbc572e624\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\",\"title\":\"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9020727135483795264",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"gitiles_ref:refs/heads/master"
]
}
[18:34:00.825] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1613327639313671",
"id": "8855297073238243904",
"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\": \"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5b1f6c95704da7128ca2a7c682a2e0ef533180ce\", \"title\": \"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@0d0a0d86c841a95226d9cc7c247c770c38fdc81b\", \"title\": \"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1944212b426d2e13cc4bb24aa1a850bbc572e624\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1944212b426d2e13cc4bb24aa1a850bbc572e624\", \"title\": \"1944212b426d2e13cc4bb24aa1a850bbc572e624\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/1944212b426d2e13cc4bb24aa1a850bbc572e624\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\", \"title\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613327640418456",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020727135483795264",
"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/8855297073238243904/+/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": "1613327640418572",
"url": "https://ci.chromium.org/b/8855297073238243904",
"utcnow_ts": "1613327640809217"
}
}
[18:34:00.825] Task URL: https://ci.chromium.org/b/8855297073238243904
[18:34:00.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:2:0) after 1m0s
[18:34:51.014] Received PubSub notification, asking Buildbucket for the build status
[18:34:51.138] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:01.388] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:2:0)
[18:35:01.388] Timer tick, asking Buildbucket for the build status
[18:35:01.464] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:01.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:4:0) after 1m0s
[18:36:01.751] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:4:0)
[18:36:01.751] Timer tick, asking Buildbucket for the build status
[18:36:01.856] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:01.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:5:0) after 1m0s
[18:37:01.914] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:5:0)
[18:37:01.914] Timer tick, asking Buildbucket for the build status
[18:37:01.995] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:01.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:6:0) after 1m0s
[18:38:02.107] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:6:0)
[18:38:02.107] Timer tick, asking Buildbucket for the build status
[18:38:02.254] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:02.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:7:0) after 1m0s
[18:39:02.530] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:7:0)
[18:39:02.530] Timer tick, asking Buildbucket for the build status
[18:39:02.902] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:02.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:8:0) after 1m0s
[18:40:02.981] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:8:0)
[18:40:02.981] Timer tick, asking Buildbucket for the build status
[18:40:03.067] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:03.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:9:0) after 1m0s
[18:41:03.245] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:9:0)
[18:41:03.245] Timer tick, asking Buildbucket for the build status
[18:41:03.371] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:03.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:10:0) after 1m0s
[18:42:03.437] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:10:0)
[18:42:03.437] Timer tick, asking Buildbucket for the build status
[18:42:03.526] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:03.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:11:0) after 1m0s
[18:43:03.575] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:11:0)
[18:43:03.575] Timer tick, asking Buildbucket for the build status
[18:43:03.669] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:03.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:12:0) after 1m0s
[18:44:03.802] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:12:0)
[18:44:03.802] Timer tick, asking Buildbucket for the build status
[18:44:03.913] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:03.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:13:0) after 1m0s
[18:45:03.917] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:13:0)
[18:45:03.917] Timer tick, asking Buildbucket for the build status
[18:45:04.459] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:04.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:14:0) after 1m0s
[18:46:04.621] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:14:0)
[18:46:04.621] Timer tick, asking Buildbucket for the build status
[18:46:04.989] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:04.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:15:0) after 1m0s
[18:47:05.100] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:15:0)
[18:47:05.100] Timer tick, asking Buildbucket for the build status
[18:47:05.495] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:05.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:16:0) after 1m0s
[18:48:05.631] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:16:0)
[18:48:05.631] Timer tick, asking Buildbucket for the build status
[18:48:05.917] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:05.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:17:0) after 1m0s
[18:49:05.995] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:17:0)
[18:49:05.995] Timer tick, asking Buildbucket for the build status
[18:49:06.101] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:06.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:18:0) after 1m0s
[18:50:06.207] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:18:0)
[18:50:06.207] Timer tick, asking Buildbucket for the build status
[18:50:06.307] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:06.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:19:0) after 1m0s
[18:51:06.337] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:19:0)
[18:51:06.337] Timer tick, asking Buildbucket for the build status
[18:51:06.455] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:06.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:20:0) after 1m0s
[18:52:06.472] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:20:0)
[18:52:06.472] Timer tick, asking Buildbucket for the build status
[18:52:06.553] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:06.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:21:0) after 1m0s
[18:53:06.602] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:21:0)
[18:53:06.602] Timer tick, asking Buildbucket for the build status
[18:53:06.916] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:06.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:22:0) after 1m0s
[18:54:06.974] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:22:0)
[18:54:06.974] Timer tick, asking Buildbucket for the build status
[18:54:07.077] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:07.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:23:0) after 1m0s
[18:55:07.096] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:23:0)
[18:55:07.096] Timer tick, asking Buildbucket for the build status
[18:55:07.325] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:07.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:24:0) after 1m0s
[18:56:07.397] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:24:0)
[18:56:07.397] Timer tick, asking Buildbucket for the build status
[18:56:07.898] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:07.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:25:0) after 1m0s
[18:57:07.917] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:25:0)
[18:57:07.917] Timer tick, asking Buildbucket for the build status
[18:57:07.992] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:07.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:26:0) after 1m0s
[18:58:08.083] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:26:0)
[18:58:08.083] Timer tick, asking Buildbucket for the build status
[18:58:08.409] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:08.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:27:0) after 1m0s
[18:59:08.418] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:27:0)
[18:59:08.418] Timer tick, asking Buildbucket for the build status
[18:59:08.511] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:08.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:28:0) after 1m0s
[19:00:08.482] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:28:0)
[19:00:08.482] Timer tick, asking Buildbucket for the build status
[19:00:08.569] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:08.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:29:0) after 1m0s
[19:01:08.665] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:29:0)
[19:01:08.665] Timer tick, asking Buildbucket for the build status
[19:01:08.884] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:08.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:30:0) after 1m0s
[19:02:08.902] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:30:0)
[19:02:08.902] Timer tick, asking Buildbucket for the build status
[19:02:08.993] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:08.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:31:0) after 1m0s
[19:03:09.015] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:31:0)
[19:03:09.015] Timer tick, asking Buildbucket for the build status
[19:03:09.140] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:09.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:32:0) after 1m0s
[19:04:09.216] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:32:0)
[19:04:09.216] Timer tick, asking Buildbucket for the build status
[19:04:09.305] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:09.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:33:0) after 1m0s
[19:05:09.342] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:33:0)
[19:05:09.342] Timer tick, asking Buildbucket for the build status
[19:05:09.706] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:09.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:34:0) after 1m0s
[19:06:09.726] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:34:0)
[19:06:09.726] Timer tick, asking Buildbucket for the build status
[19:06:09.849] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:09.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:35:0) after 1m0s
[19:07:09.870] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:35:0)
[19:07:09.870] Timer tick, asking Buildbucket for the build status
[19:07:09.945] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:09.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:36:0) after 1m0s
[19:08:09.955] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:36:0)
[19:08:09.955] Timer tick, asking Buildbucket for the build status
[19:08:10.045] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:10.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:37:0) after 1m0s
[19:09:10.076] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:37:0)
[19:09:10.076] Timer tick, asking Buildbucket for the build status
[19:09:10.249] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:10.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:38:0) after 1m0s
[19:10:10.269] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:38:0)
[19:10:10.269] Timer tick, asking Buildbucket for the build status
[19:10:10.452] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:10.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:39:0) after 1m0s
[19:11:10.566] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:39:0)
[19:11:10.566] Timer tick, asking Buildbucket for the build status
[19:11:10.688] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:10.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:40:0) after 1m0s
[19:12:10.781] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:40:0)
[19:12:10.781] Timer tick, asking Buildbucket for the build status
[19:12:11.170] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:11.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:41:0) after 1m0s
[19:13:11.230] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:41:0)
[19:13:11.230] Timer tick, asking Buildbucket for the build status
[19:13:11.326] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:11.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:42:0) after 1m0s
[19:14:11.385] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:42:0)
[19:14:11.385] Timer tick, asking Buildbucket for the build status
[19:14:11.489] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:11.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:43:0) after 1m0s
[19:15:11.656] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:43:0)
[19:15:11.656] Timer tick, asking Buildbucket for the build status
[19:15:12.050] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:12.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:44:0) after 1m0s
[19:16:12.164] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:44:0)
[19:16:12.164] Timer tick, asking Buildbucket for the build status
[19:16:12.595] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:12.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:45:0) after 1m0s
[19:17:12.656] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:45:0)
[19:17:12.656] Timer tick, asking Buildbucket for the build status
[19:17:12.867] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:12.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:46:0) after 1m0s
[19:18:12.899] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:46:0)
[19:18:12.899] Timer tick, asking Buildbucket for the build status
[19:18:12.993] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:12.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:47:0) after 1m0s
[19:19:13.072] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:47:0)
[19:19:13.072] Timer tick, asking Buildbucket for the build status
[19:19:13.176] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:13.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:48:0) after 1m0s
[19:20:13.196] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:48:0)
[19:20:13.196] Timer tick, asking Buildbucket for the build status
[19:20:13.339] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:13.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:49:0) after 1m0s
[19:21:13.488] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:49:0)
[19:21:13.488] Timer tick, asking Buildbucket for the build status
[19:21:13.565] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:13.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:50:0) after 1m0s
[19:22:13.755] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:50:0)
[19:22:13.755] Timer tick, asking Buildbucket for the build status
[19:22:13.950] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:13.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:51:0) after 1m0s
[19:23:14.169] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:51:0)
[19:23:14.169] Timer tick, asking Buildbucket for the build status
[19:23:14.654] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:14.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:52:0) after 1m0s
[19:24:14.815] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:52:0)
[19:24:14.815] Timer tick, asking Buildbucket for the build status
[19:24:14.921] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:14.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:53:0) after 1m0s
[19:25:14.977] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:53:0)
[19:25:14.977] Timer tick, asking Buildbucket for the build status
[19:25:15.174] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:15.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:54:0) after 1m0s
[19:26:15.305] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:54:0)
[19:26:15.305] Timer tick, asking Buildbucket for the build status
[19:26:15.403] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:15.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:55:0) after 1m0s
[19:27:15.581] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:55:0)
[19:27:15.581] Timer tick, asking Buildbucket for the build status
[19:27:15.668] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:15.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:56:0) after 1m0s
[19:28:15.740] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:56:0)
[19:28:15.740] Timer tick, asking Buildbucket for the build status
[19:28:15.842] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:15.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:57:0) after 1m0s
[19:29:15.942] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:57:0)
[19:29:15.942] Timer tick, asking Buildbucket for the build status
[19:29:16.049] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:16.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:58:0) after 1m0s
[19:30:16.031] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:58:0)
[19:30:16.031] Timer tick, asking Buildbucket for the build status
[19:30:16.114] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:16.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:59:0) after 1m0s
[19:31:16.240] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:59:0)
[19:31:16.240] Timer tick, asking Buildbucket for the build status
[19:31:16.311] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:16.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:60:0) after 1m0s
[19:32:16.329] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:60:0)
[19:32:16.329] Timer tick, asking Buildbucket for the build status
[19:32:16.411] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:16.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:61:0) after 1m0s
[19:33:16.472] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:61:0)
[19:33:16.472] Timer tick, asking Buildbucket for the build status
[19:33:16.776] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:16.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:62:0) after 1m0s
[19:34:16.801] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:62:0)
[19:34:16.801] Timer tick, asking Buildbucket for the build status
[19:34:16.929] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:16.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:63:0) after 1m0s
[19:35:16.978] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:63:0)
[19:35:16.978] Timer tick, asking Buildbucket for the build status
[19:35:17.055] Build 8855297073238243904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:17.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9020727135483795264:64:0) after 1m0s
[19:36:15.410] Received PubSub notification, asking Buildbucket for the build status
[19:36:15.612] Build 8855297073238243904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:36:15.612] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1613331374386004",
"created_by": "project:fuchsia",
"created_ts": "1613327639313671",
"id": "8855297073238243904",
"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\": \"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5b1f6c95704da7128ca2a7c682a2e0ef533180ce\", \"title\": \"5b1f6c95704da7128ca2a7c682a2e0ef533180ce\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5b1f6c95704da7128ca2a7c682a2e0ef533180ce\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@0d0a0d86c841a95226d9cc7c247c770c38fdc81b\", \"title\": \"0d0a0d86c841a95226d9cc7c247c770c38fdc81b\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/0d0a0d86c841a95226d9cc7c247c770c38fdc81b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1944212b426d2e13cc4bb24aa1a850bbc572e624\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1944212b426d2e13cc4bb24aa1a850bbc572e624\", \"title\": \"1944212b426d2e13cc4bb24aa1a850bbc572e624\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/1944212b426d2e13cc4bb24aa1a850bbc572e624\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\", \"title\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"d7a7af8ff45510c2ac1721d65229ed7d89f08c6a\"}}",
"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-f-0-65qx\"], \"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.7.13\"], \"server_version\": [\"5690-40331dd\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1613327689674005",
"status": "COMPLETED",
"status_changed_ts": "1613327690128377",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/d7a7af8ff45510c2ac1721d65229ed7d89f08c6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020727135483795264",
"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/8855297073238243904/+/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:51baa3b25aca0f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613331374426687",
"url": "https://ci.chromium.org/b/8855297073238243904",
"utcnow_ts": "1613331375602684"
}
}
[19:36:15.612] Invocation finished in 1h2m17.823216608s with status SUCCEEDED