[18:33:44.432] New invocation is queued and will start shortly
[18:33:45.647] Starting the invocation (attempt 1)
[18:33:45.737] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:45.737] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:45.737] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9021814313112240848",
"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\":\"9918bff7cf2a566aaa2036d2242a61c140652dc7\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@9918bff7cf2a566aaa2036d2242a61c140652dc7\",\"title\":\"9918bff7cf2a566aaa2036d2242a61c140652dc7\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/9918bff7cf2a566aaa2036d2242a61c140652dc7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\",\"title\":\"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"962effa79018f57766d9aee21493f616801778dc\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@962effa79018f57766d9aee21493f616801778dc\",\"title\":\"962effa79018f57766d9aee21493f616801778dc\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/962effa79018f57766d9aee21493f616801778dc\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\",\"title\":\"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9021814313112240848",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"gitiles_ref:refs/heads/master"
]
}
[18:33:47.320] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1612290825895400",
"id": "8856384250904778000",
"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\": \"9918bff7cf2a566aaa2036d2242a61c140652dc7\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@9918bff7cf2a566aaa2036d2242a61c140652dc7\", \"title\": \"9918bff7cf2a566aaa2036d2242a61c140652dc7\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/9918bff7cf2a566aaa2036d2242a61c140652dc7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\", \"title\": \"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"962effa79018f57766d9aee21493f616801778dc\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@962effa79018f57766d9aee21493f616801778dc\", \"title\": \"962effa79018f57766d9aee21493f616801778dc\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/962effa79018f57766d9aee21493f616801778dc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\", \"title\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612290826744817",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021814313112240848",
"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/8856384250904778000/+/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": "1612290826744927",
"url": "https://ci.chromium.org/b/8856384250904778000",
"utcnow_ts": "1612290827259926"
}
}
[18:33:47.320] Task URL: https://ci.chromium.org/b/8856384250904778000
[18:33:47.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:2:0) after 1m0s
[18:34:01.935] Received PubSub notification, asking Buildbucket for the build status
[18:34:02.123] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:47.441] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:2:0)
[18:34:47.441] Timer tick, asking Buildbucket for the build status
[18:34:47.546] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:47.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:4:0) after 1m0s
[18:35:47.582] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:4:0)
[18:35:47.582] Timer tick, asking Buildbucket for the build status
[18:35:47.766] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:47.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:5:0) after 1m0s
[18:36:47.835] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:5:0)
[18:36:47.835] Timer tick, asking Buildbucket for the build status
[18:36:48.052] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:48.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:6:0) after 1m0s
[18:37:48.133] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:6:0)
[18:37:48.133] Timer tick, asking Buildbucket for the build status
[18:37:48.225] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:48.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:7:0) after 1m0s
[18:38:48.282] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:7:0)
[18:38:48.282] Timer tick, asking Buildbucket for the build status
[18:38:48.406] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:48.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:8:0) after 1m0s
[18:39:48.482] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:8:0)
[18:39:48.482] Timer tick, asking Buildbucket for the build status
[18:39:48.635] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:48.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:9:0) after 1m0s
[18:40:48.868] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:9:0)
[18:40:48.868] Timer tick, asking Buildbucket for the build status
[18:40:48.969] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:48.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:10:0) after 1m0s
[18:41:49.311] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:10:0)
[18:41:49.311] Timer tick, asking Buildbucket for the build status
[18:41:49.554] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:49.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:11:0) after 1m0s
[18:42:49.589] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:11:0)
[18:42:49.589] Timer tick, asking Buildbucket for the build status
[18:42:49.917] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:49.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:12:0) after 1m0s
[18:43:49.922] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:12:0)
[18:43:49.922] Timer tick, asking Buildbucket for the build status
[18:43:50.433] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:50.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:13:0) after 1m0s
[18:44:50.547] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:13:0)
[18:44:50.547] Timer tick, asking Buildbucket for the build status
[18:44:50.622] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:50.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:14:0) after 1m0s
[18:45:50.649] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:14:0)
[18:45:50.649] Timer tick, asking Buildbucket for the build status
[18:45:50.914] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:15:0) after 1m0s
[18:46:50.944] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:15:0)
[18:46:50.944] Timer tick, asking Buildbucket for the build status
[18:46:51.031] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:51.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:16:0) after 1m0s
[18:47:51.068] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:16:0)
[18:47:51.068] Timer tick, asking Buildbucket for the build status
[18:47:51.651] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:51.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:17:0) after 1m0s
[18:48:51.636] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:17:0)
[18:48:51.636] Timer tick, asking Buildbucket for the build status
[18:48:51.791] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:51.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:18:0) after 1m0s
[18:49:51.854] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:18:0)
[18:49:51.854] Timer tick, asking Buildbucket for the build status
[18:49:52.026] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:52.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:19:0) after 1m0s
[18:50:52.089] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:19:0)
[18:50:52.089] Timer tick, asking Buildbucket for the build status
[18:50:52.286] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:52.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:20:0) after 1m0s
[18:51:52.345] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:20:0)
[18:51:52.345] Timer tick, asking Buildbucket for the build status
[18:51:52.939] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:52.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:21:0) after 1m0s
[18:52:52.985] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:21:0)
[18:52:52.985] Timer tick, asking Buildbucket for the build status
[18:52:53.619] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:53.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:22:0) after 1m0s
[18:53:53.706] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:22:0)
[18:53:53.706] Timer tick, asking Buildbucket for the build status
[18:53:53.826] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:53.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:23:0) after 1m0s
[18:54:53.879] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:23:0)
[18:54:53.879] Timer tick, asking Buildbucket for the build status
[18:54:54.335] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:54.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:24:0) after 1m0s
[18:55:54.482] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:24:0)
[18:55:54.482] Timer tick, asking Buildbucket for the build status
[18:55:54.600] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:54.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:25:0) after 1m0s
[18:56:54.689] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:25:0)
[18:56:54.689] Timer tick, asking Buildbucket for the build status
[18:56:54.780] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:54.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:26:0) after 1m0s
[18:57:54.823] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:26:0)
[18:57:54.823] Timer tick, asking Buildbucket for the build status
[18:57:54.894] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:54.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:27:0) after 1m0s
[18:58:54.926] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:27:0)
[18:58:54.926] Timer tick, asking Buildbucket for the build status
[18:58:55.058] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:55.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:28:0) after 1m0s
[18:59:55.194] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:28:0)
[18:59:55.194] Timer tick, asking Buildbucket for the build status
[18:59:55.527] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:55.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:29:0) after 1m0s
[19:00:55.598] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:29:0)
[19:00:55.598] Timer tick, asking Buildbucket for the build status
[19:00:55.678] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:55.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:30:0) after 1m0s
[19:01:55.733] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:30:0)
[19:01:55.733] Timer tick, asking Buildbucket for the build status
[19:01:55.802] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:55.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:31:0) after 1m0s
[19:02:55.874] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:31:0)
[19:02:55.874] Timer tick, asking Buildbucket for the build status
[19:02:56.017] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:56.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:32:0) after 1m0s
[19:03:56.118] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:32:0)
[19:03:56.118] Timer tick, asking Buildbucket for the build status
[19:03:56.458] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:56.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:33:0) after 1m0s
[19:04:56.548] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:33:0)
[19:04:56.548] Timer tick, asking Buildbucket for the build status
[19:04:56.642] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:56.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:34:0) after 1m0s
[19:05:56.696] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:34:0)
[19:05:56.696] Timer tick, asking Buildbucket for the build status
[19:05:56.766] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:56.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:35:0) after 1m0s
[19:06:56.848] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:35:0)
[19:06:56.848] Timer tick, asking Buildbucket for the build status
[19:06:56.938] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:56.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:36:0) after 1m0s
[19:07:57.015] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:36:0)
[19:07:57.015] Timer tick, asking Buildbucket for the build status
[19:07:57.086] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:57.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:37:0) after 1m0s
[19:08:57.223] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:37:0)
[19:08:57.223] Timer tick, asking Buildbucket for the build status
[19:08:57.367] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:57.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:38:0) after 1m0s
[19:09:57.397] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:38:0)
[19:09:57.397] Timer tick, asking Buildbucket for the build status
[19:09:57.475] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:57.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:39:0) after 1m0s
[19:10:57.508] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:39:0)
[19:10:57.508] Timer tick, asking Buildbucket for the build status
[19:10:57.577] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:57.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:40:0) after 1m0s
[19:11:57.619] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:40:0)
[19:11:57.619] Timer tick, asking Buildbucket for the build status
[19:11:57.708] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:57.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:41:0) after 1m0s
[19:12:57.708] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:41:0)
[19:12:57.708] Timer tick, asking Buildbucket for the build status
[19:12:57.998] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:57.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:42:0) after 1m0s
[19:13:58.011] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:42:0)
[19:13:58.011] Timer tick, asking Buildbucket for the build status
[19:13:58.123] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:58.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:43:0) after 1m0s
[19:14:58.150] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:43:0)
[19:14:58.150] Timer tick, asking Buildbucket for the build status
[19:14:58.659] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:58.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:44:0) after 1m0s
[19:15:58.676] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:44:0)
[19:15:58.676] Timer tick, asking Buildbucket for the build status
[19:15:58.733] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:58.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:45:0) after 1m0s
[19:16:58.726] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:45:0)
[19:16:58.726] Timer tick, asking Buildbucket for the build status
[19:16:58.955] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:58.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:46:0) after 1m0s
[19:17:59.224] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:46:0)
[19:17:59.224] Timer tick, asking Buildbucket for the build status
[19:17:59.300] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:59.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:47:0) after 1m0s
[19:18:59.540] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:47:0)
[19:18:59.540] Timer tick, asking Buildbucket for the build status
[19:18:59.690] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:59.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:48:0) after 1m0s
[19:19:59.889] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:48:0)
[19:19:59.889] Timer tick, asking Buildbucket for the build status
[19:19:59.949] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:59.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:49:0) after 1m0s
[19:21:00.135] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:49:0)
[19:21:00.135] Timer tick, asking Buildbucket for the build status
[19:21:00.712] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:00.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:50:0) after 1m0s
[19:22:00.791] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:50:0)
[19:22:00.791] Timer tick, asking Buildbucket for the build status
[19:22:00.860] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:00.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:51:0) after 1m0s
[19:23:01.502] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:51:0)
[19:23:01.502] Timer tick, asking Buildbucket for the build status
[19:23:01.616] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:01.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:52:0) after 1m0s
[19:24:01.928] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:52:0)
[19:24:01.928] Timer tick, asking Buildbucket for the build status
[19:24:02.142] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:02.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:53:0) after 1m0s
[19:25:02.168] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:53:0)
[19:25:02.168] Timer tick, asking Buildbucket for the build status
[19:25:02.253] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:02.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:54:0) after 1m0s
[19:26:02.372] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:54:0)
[19:26:02.372] Timer tick, asking Buildbucket for the build status
[19:26:02.666] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:02.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:55:0) after 1m0s
[19:27:02.789] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:55:0)
[19:27:02.789] Timer tick, asking Buildbucket for the build status
[19:27:02.873] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:02.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:56:0) after 1m0s
[19:28:02.908] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:56:0)
[19:28:02.908] Timer tick, asking Buildbucket for the build status
[19:28:03.321] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:57:0) after 1m0s
[19:29:03.376] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:57:0)
[19:29:03.376] Timer tick, asking Buildbucket for the build status
[19:29:03.507] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:03.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:58:0) after 1m0s
[19:30:03.551] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:58:0)
[19:30:03.551] Timer tick, asking Buildbucket for the build status
[19:30:04.955] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:04.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:59:0) after 1m0s
[19:31:05.077] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:59:0)
[19:31:05.077] Timer tick, asking Buildbucket for the build status
[19:31:05.151] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:05.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:60:0) after 1m0s
[19:32:05.195] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:60:0)
[19:32:05.195] Timer tick, asking Buildbucket for the build status
[19:32:05.328] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:05.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:61:0) after 1m0s
[19:33:05.393] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:61:0)
[19:33:05.393] Timer tick, asking Buildbucket for the build status
[19:33:05.461] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:05.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:62:0) after 1m0s
[19:34:05.544] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:62:0)
[19:34:05.544] Timer tick, asking Buildbucket for the build status
[19:34:05.704] Build 8856384250904778000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:05.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021814313112240848:63:0) after 1m0s
[19:34:29.551] Received PubSub notification, asking Buildbucket for the build status
[19:34:29.714] Build 8856384250904778000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:34:29.714] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1612294468781206",
"created_by": "project:fuchsia",
"created_ts": "1612290825895400",
"id": "8856384250904778000",
"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\": \"9918bff7cf2a566aaa2036d2242a61c140652dc7\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@9918bff7cf2a566aaa2036d2242a61c140652dc7\", \"title\": \"9918bff7cf2a566aaa2036d2242a61c140652dc7\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/9918bff7cf2a566aaa2036d2242a61c140652dc7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\", \"title\": \"5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/5424d7ed94cf5a7ca24636ab9f4e6d5c353fc0d3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"962effa79018f57766d9aee21493f616801778dc\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@962effa79018f57766d9aee21493f616801778dc\", \"title\": \"962effa79018f57766d9aee21493f616801778dc\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/962effa79018f57766d9aee21493f616801778dc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\", \"title\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"ecfda20dcc4b658560cd4f0178f6c9cd569d4cad\"}}",
"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-a-0-oy3r\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1612290840537414",
"status": "COMPLETED",
"status_changed_ts": "1612294469293560",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/ecfda20dcc4b658560cd4f0178f6c9cd569d4cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021814313112240848",
"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/8856384250904778000/+/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:517cd731c09ae610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612294469332821",
"url": "https://ci.chromium.org/b/8856384250904778000",
"utcnow_ts": "1612294469680878"
}
}
[19:34:29.714] Invocation finished in 1h0m45.308223739s with status SUCCEEDED