[18:33:56.820] New invocation is queued and will start shortly
[18:33:57.983] Starting the invocation (attempt 1)
[18:33:58.018] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:58.018] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:58.018] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9021451912246459760",
"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\":\"aa09469fc64467d1748c6041befb55bbad9e2328\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@aa09469fc64467d1748c6041befb55bbad9e2328\",\"title\":\"aa09469fc64467d1748c6041befb55bbad9e2328\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/aa09469fc64467d1748c6041befb55bbad9e2328\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"7a36eeea266e41fba314ddaf27782abd903cf71e\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a36eeea266e41fba314ddaf27782abd903cf71e\",\"title\":\"7a36eeea266e41fba314ddaf27782abd903cf71e\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/7a36eeea266e41fba314ddaf27782abd903cf71e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"8e25beb4af30830149248c33771452354c48a8b7\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@8e25beb4af30830149248c33771452354c48a8b7\",\"title\":\"8e25beb4af30830149248c33771452354c48a8b7\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/8e25beb4af30830149248c33771452354c48a8b7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"cd89c53f6d475cd05a1858b9195770e347fb2e9e\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@cd89c53f6d475cd05a1858b9195770e347fb2e9e\",\"title\":\"cd89c53f6d475cd05a1858b9195770e347fb2e9e\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/cd89c53f6d475cd05a1858b9195770e347fb2e9e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\",\"title\":\"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"7a9bd3b4e219c5cd892757f86c2075ec67333903\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a9bd3b4e219c5cd892757f86c2075ec67333903\",\"title\":\"7a9bd3b4e219c5cd892757f86c2075ec67333903\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"7a9bd3b4e219c5cd892757f86c2075ec67333903\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9021451912246459760",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"gitiles_ref:refs/heads/master"
]
}
[18:33:58.496] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1612636438104926",
"id": "8856021850237274640",
"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\": \"aa09469fc64467d1748c6041befb55bbad9e2328\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@aa09469fc64467d1748c6041befb55bbad9e2328\", \"title\": \"aa09469fc64467d1748c6041befb55bbad9e2328\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/aa09469fc64467d1748c6041befb55bbad9e2328\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a36eeea266e41fba314ddaf27782abd903cf71e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a36eeea266e41fba314ddaf27782abd903cf71e\", \"title\": \"7a36eeea266e41fba314ddaf27782abd903cf71e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/7a36eeea266e41fba314ddaf27782abd903cf71e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"8e25beb4af30830149248c33771452354c48a8b7\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@8e25beb4af30830149248c33771452354c48a8b7\", \"title\": \"8e25beb4af30830149248c33771452354c48a8b7\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/8e25beb4af30830149248c33771452354c48a8b7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"cd89c53f6d475cd05a1858b9195770e347fb2e9e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@cd89c53f6d475cd05a1858b9195770e347fb2e9e\", \"title\": \"cd89c53f6d475cd05a1858b9195770e347fb2e9e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/cd89c53f6d475cd05a1858b9195770e347fb2e9e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\", \"title\": \"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a9bd3b4e219c5cd892757f86c2075ec67333903\", \"title\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612636438368007",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021451912246459760",
"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/8856021850237274640/+/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": "1612636438368120",
"url": "https://ci.chromium.org/b/8856021850237274640",
"utcnow_ts": "1612636438486946"
}
}
[18:33:58.496] Task URL: https://ci.chromium.org/b/8856021850237274640
[18:33:58.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:2:0) after 1m0s
[18:34:01.946] Received PubSub notification, asking Buildbucket for the build status
[18:34:02.026] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:58.555] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:2:0)
[18:34:58.555] Timer tick, asking Buildbucket for the build status
[18:34:58.669] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:58.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:4:0) after 1m0s
[18:35:58.699] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:4:0)
[18:35:58.699] Timer tick, asking Buildbucket for the build status
[18:35:59.328] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:59.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:5:0) after 1m0s
[18:36:59.432] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:5:0)
[18:36:59.432] Timer tick, asking Buildbucket for the build status
[18:36:59.503] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:59.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:6:0) after 1m0s
[18:37:59.525] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:6:0)
[18:37:59.525] Timer tick, asking Buildbucket for the build status
[18:37:59.605] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:59.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:7:0) after 1m0s
[18:38:59.664] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:7:0)
[18:38:59.664] Timer tick, asking Buildbucket for the build status
[18:38:59.761] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:59.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:8:0) after 1m0s
[18:39:59.847] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:8:0)
[18:39:59.847] Timer tick, asking Buildbucket for the build status
[18:40:00.189] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:00.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:9:0) after 1m0s
[18:41:00.208] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:9:0)
[18:41:00.208] Timer tick, asking Buildbucket for the build status
[18:41:02.145] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:02.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:10:0) after 1m0s
[18:42:02.215] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:10:0)
[18:42:02.215] Timer tick, asking Buildbucket for the build status
[18:42:02.410] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:02.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:11:0) after 1m0s
[18:43:02.428] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:11:0)
[18:43:02.428] Timer tick, asking Buildbucket for the build status
[18:43:02.584] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:02.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:12:0) after 1m0s
[18:44:02.602] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:12:0)
[18:44:02.602] Timer tick, asking Buildbucket for the build status
[18:44:02.952] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:02.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:13:0) after 1m0s
[18:45:03.016] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:13:0)
[18:45:03.016] Timer tick, asking Buildbucket for the build status
[18:45:03.218] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:03.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:14:0) after 1m0s
[18:46:03.263] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:14:0)
[18:46:03.263] Timer tick, asking Buildbucket for the build status
[18:46:03.358] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:03.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:15:0) after 1m0s
[18:47:03.378] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:15:0)
[18:47:03.378] Timer tick, asking Buildbucket for the build status
[18:47:03.460] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:03.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:16:0) after 1m0s
[18:48:03.479] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:16:0)
[18:48:03.479] Timer tick, asking Buildbucket for the build status
[18:48:03.612] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:03.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:17:0) after 1m0s
[18:49:03.781] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:17:0)
[18:49:03.781] Timer tick, asking Buildbucket for the build status
[18:49:04.619] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:04.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:18:0) after 1m0s
[18:50:04.734] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:18:0)
[18:50:04.734] Timer tick, asking Buildbucket for the build status
[18:50:05.592] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:05.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:19:0) after 1m0s
[18:51:05.655] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:19:0)
[18:51:05.655] Timer tick, asking Buildbucket for the build status
[18:51:05.949] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:05.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:20:0) after 1m0s
[18:52:06.016] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:20:0)
[18:52:06.016] Timer tick, asking Buildbucket for the build status
[18:52:06.498] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:06.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:21:0) after 1m0s
[18:53:06.513] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:21:0)
[18:53:06.513] Timer tick, asking Buildbucket for the build status
[18:53:06.625] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:06.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:22:0) after 1m0s
[18:54:06.830] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:22:0)
[18:54:06.830] Timer tick, asking Buildbucket for the build status
[18:54:06.949] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:06.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:23:0) after 1m0s
[18:55:07.225] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:23:0)
[18:55:07.225] Timer tick, asking Buildbucket for the build status
[18:55:07.397] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:07.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:24:0) after 1m0s
[18:56:07.638] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:24:0)
[18:56:07.638] Timer tick, asking Buildbucket for the build status
[18:56:08.091] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:08.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:25:0) after 1m0s
[18:57:08.131] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:25:0)
[18:57:08.131] Timer tick, asking Buildbucket for the build status
[18:57:08.542] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:08.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:26:0) after 1m0s
[18:58:08.793] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:26:0)
[18:58:08.793] Timer tick, asking Buildbucket for the build status
[18:58:09.173] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:09.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:27:0) after 1m0s
[18:59:09.329] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:27:0)
[18:59:09.329] Timer tick, asking Buildbucket for the build status
[18:59:09.444] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:09.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:28:0) after 1m0s
[19:00:09.805] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:28:0)
[19:00:09.805] Timer tick, asking Buildbucket for the build status
[19:00:09.966] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:09.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:29:0) after 1m0s
[19:01:10.471] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:29:0)
[19:01:10.471] Timer tick, asking Buildbucket for the build status
[19:01:10.542] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:10.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:30:0) after 1m0s
[19:02:11.011] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:30:0)
[19:02:11.011] Timer tick, asking Buildbucket for the build status
[19:02:11.416] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:11.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:31:0) after 1m0s
[19:03:12.385] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:31:0)
[19:03:12.385] Timer tick, asking Buildbucket for the build status
[19:03:12.917] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:12.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:32:0) after 1m0s
[19:04:13.078] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:32:0)
[19:04:13.078] Timer tick, asking Buildbucket for the build status
[19:04:13.698] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:13.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:33:0) after 1m0s
[19:05:13.793] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:33:0)
[19:05:13.793] Timer tick, asking Buildbucket for the build status
[19:05:13.986] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:13.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:34:0) after 1m0s
[19:06:14.261] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:34:0)
[19:06:14.261] Timer tick, asking Buildbucket for the build status
[19:06:14.329] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:14.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:35:0) after 1m0s
[19:07:14.441] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:35:0)
[19:07:14.441] Timer tick, asking Buildbucket for the build status
[19:07:14.564] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:14.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:36:0) after 1m0s
[19:08:14.889] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:36:0)
[19:08:14.889] Timer tick, asking Buildbucket for the build status
[19:08:15.111] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:15.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:37:0) after 1m0s
[19:09:15.301] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:37:0)
[19:09:15.301] Timer tick, asking Buildbucket for the build status
[19:09:16.197] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:16.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:38:0) after 1m0s
[19:10:16.398] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:38:0)
[19:10:16.398] Timer tick, asking Buildbucket for the build status
[19:10:16.619] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:16.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:39:0) after 1m0s
[19:11:16.903] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:39:0)
[19:11:16.903] Timer tick, asking Buildbucket for the build status
[19:11:16.967] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:16.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:40:0) after 1m0s
[19:12:17.187] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:40:0)
[19:12:17.187] Timer tick, asking Buildbucket for the build status
[19:12:19.264] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:19.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:41:0) after 1m0s
[19:13:19.347] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:41:0)
[19:13:19.347] Timer tick, asking Buildbucket for the build status
[19:13:19.435] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:19.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:42:0) after 1m0s
[19:14:19.542] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:42:0)
[19:14:19.542] Timer tick, asking Buildbucket for the build status
[19:14:19.680] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:19.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:43:0) after 1m0s
[19:15:19.681] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:43:0)
[19:15:19.681] Timer tick, asking Buildbucket for the build status
[19:15:21.322] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:21.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:44:0) after 1m0s
[19:16:21.378] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:44:0)
[19:16:21.378] Timer tick, asking Buildbucket for the build status
[19:16:21.476] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:21.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:45:0) after 1m0s
[19:17:21.535] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:45:0)
[19:17:21.535] Timer tick, asking Buildbucket for the build status
[19:17:21.606] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:21.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:46:0) after 1m0s
[19:18:21.645] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:46:0)
[19:18:21.645] Timer tick, asking Buildbucket for the build status
[19:18:21.827] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:21.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:47:0) after 1m0s
[19:19:21.847] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:47:0)
[19:19:21.847] Timer tick, asking Buildbucket for the build status
[19:19:21.982] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:21.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:48:0) after 1m0s
[19:20:22.009] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:48:0)
[19:20:22.009] Timer tick, asking Buildbucket for the build status
[19:20:22.217] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:22.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:49:0) after 1m0s
[19:21:22.237] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:49:0)
[19:21:22.237] Timer tick, asking Buildbucket for the build status
[19:21:22.480] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:22.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:50:0) after 1m0s
[19:22:22.531] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:50:0)
[19:22:22.531] Timer tick, asking Buildbucket for the build status
[19:22:22.751] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:22.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:51:0) after 1m0s
[19:23:22.760] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:51:0)
[19:23:22.760] Timer tick, asking Buildbucket for the build status
[19:23:22.833] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:22.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:52:0) after 1m0s
[19:24:22.868] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:52:0)
[19:24:22.868] Timer tick, asking Buildbucket for the build status
[19:24:23.039] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:23.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:53:0) after 1m0s
[19:25:23.117] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:53:0)
[19:25:23.117] Timer tick, asking Buildbucket for the build status
[19:25:23.193] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:23.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:54:0) after 1m0s
[19:26:23.175] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:54:0)
[19:26:23.175] Timer tick, asking Buildbucket for the build status
[19:26:23.399] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:23.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:55:0) after 1m0s
[19:27:23.422] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:55:0)
[19:27:23.422] Timer tick, asking Buildbucket for the build status
[19:27:23.497] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:23.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:56:0) after 1m0s
[19:28:23.524] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:56:0)
[19:28:23.524] Timer tick, asking Buildbucket for the build status
[19:28:23.630] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:23.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:57:0) after 1m0s
[19:29:23.652] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:57:0)
[19:29:23.652] Timer tick, asking Buildbucket for the build status
[19:29:23.797] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:23.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:58:0) after 1m0s
[19:30:23.896] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:58:0)
[19:30:23.896] Timer tick, asking Buildbucket for the build status
[19:30:23.992] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:23.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:59:0) after 1m0s
[19:31:24.031] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:59:0)
[19:31:24.031] Timer tick, asking Buildbucket for the build status
[19:31:24.154] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:24.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:60:0) after 1m0s
[19:32:24.213] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:60:0)
[19:32:24.213] Timer tick, asking Buildbucket for the build status
[19:32:24.399] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:24.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:61:0) after 1m0s
[19:33:24.488] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:61:0)
[19:33:24.488] Timer tick, asking Buildbucket for the build status
[19:33:24.790] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:24.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:62:0) after 1m0s
[19:34:24.805] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:62:0)
[19:34:24.805] Timer tick, asking Buildbucket for the build status
[19:34:25.210] Build 8856021850237274640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:25.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9021451912246459760:63:0) after 1m0s
[19:34:35.504] Received PubSub notification, asking Buildbucket for the build status
[19:34:36.007] Build 8856021850237274640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:34:36.007] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1612640071482819",
"created_by": "project:fuchsia",
"created_ts": "1612636438104926",
"id": "8856021850237274640",
"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\": \"aa09469fc64467d1748c6041befb55bbad9e2328\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@aa09469fc64467d1748c6041befb55bbad9e2328\", \"title\": \"aa09469fc64467d1748c6041befb55bbad9e2328\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/aa09469fc64467d1748c6041befb55bbad9e2328\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a36eeea266e41fba314ddaf27782abd903cf71e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a36eeea266e41fba314ddaf27782abd903cf71e\", \"title\": \"7a36eeea266e41fba314ddaf27782abd903cf71e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/7a36eeea266e41fba314ddaf27782abd903cf71e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"8e25beb4af30830149248c33771452354c48a8b7\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@8e25beb4af30830149248c33771452354c48a8b7\", \"title\": \"8e25beb4af30830149248c33771452354c48a8b7\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/8e25beb4af30830149248c33771452354c48a8b7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"cd89c53f6d475cd05a1858b9195770e347fb2e9e\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@cd89c53f6d475cd05a1858b9195770e347fb2e9e\", \"title\": \"cd89c53f6d475cd05a1858b9195770e347fb2e9e\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/cd89c53f6d475cd05a1858b9195770e347fb2e9e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\", \"title\": \"4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/4c0d76b9c420bb29cb3365c5edc9c78c50d2b231\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@7a9bd3b4e219c5cd892757f86c2075ec67333903\", \"title\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"7a9bd3b4e219c5cd892757f86c2075ec67333903\"}}",
"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-6cj9\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1612636440690753",
"status": "COMPLETED",
"status_changed_ts": "1612640072670857",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/7a9bd3b4e219c5cd892757f86c2075ec67333903",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021451912246459760",
"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/8856021850237274640/+/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:519170cada48ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612640074279242",
"url": "https://ci.chromium.org/b/8856021850237274640",
"utcnow_ts": "1612640075990355"
}
}
[19:34:36.007] Invocation finished in 1h0m39.202584482s with status SUCCEEDED